builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-031 starttime: 1502450403.33 results: success (0) revision: 65826179c86e2a3538bcffd969556937e15688cd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-11 04:20:03.332863) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-11 04:20:03.333464) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-11 04:20:03.333749) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-11 04:20:03.454426) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 04:20:03.454723) ========= 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=db0483c52d987ecb441a263b000001ad-1502449052.146338-740853330 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-11 04:20:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 225M=0s 2017-08-11 04:20:04 (225 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.140972 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 04:20:03.618826) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 04:20:03.619161) ========= 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=db0483c52d987ecb441a263b000001ad-1502449052.146338-740853330 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015515 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 04:20:03.669044) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 10 secs) (at 2017-08-11 04:20:03.672456) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 65826179c86e2a3538bcffd969556937e15688cd --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 65826179c86e2a3538bcffd969556937e15688cd --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=db0483c52d987ecb441a263b000001ad-1502449052.146338-740853330 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-11 04:20:04,280 truncating revision to first 12 chars 2017-08-11 04:20:04,280 Setting DEBUG logging. 2017-08-11 04:20:04,280 attempt 1/10 2017-08-11 04:20:04,280 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/65826179c86e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-11 04:20:04,672 attempt 1/10 2017-08-11 04:20:05,292 current task status: no status available at this point. state: STARTED 2017-08-11 04:20:05,293 sleeping for 9.00s (attempt 1/10) 2017-08-11 04:20:14,302 attempt 2/10 2017-08-11 04:20:14,644 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-08-11 04:20:14,861 unpacking tar archive at: mozilla-central-65826179c86e/testing/mozharness/ program finished with exit code 0 elapsedTime=10.905374 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 10 secs) (at 2017-08-11 04:20:14.589463) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-11 04:20:14.589736) ========= script_repo_revision: 65826179c86e2a3538bcffd969556937e15688cd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-11 04:20:14.590078) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-11 04:20:14.593385) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-11 04:20:14.602297) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-11 04:20:14.602576) ========= /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=db0483c52d987ecb441a263b000001ad-1502449052.146338-740853330 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:20:15 INFO - MultiFileLogger online at 20170811 04:20:15 in /builds/slave/test 04:20:15 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 04:20:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:20:15 INFO - {'append_to_log': False, 04:20:15 INFO - 'base_work_dir': '/builds/slave/test', 04:20:15 INFO - 'blob_upload_branch': 'Firefox', 04:20:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:20:15 INFO - 'branch': 'Firefox', 04:20:15 INFO - 'buildbot_json_path': 'buildprops.json', 04:20:15 INFO - 'code_coverage': False, 04:20:15 INFO - 'config_files': ('talos/linux_config.py',), 04:20:15 INFO - 'default_actions': ('clobber', 04:20:15 INFO - 'read-buildbot-config', 04:20:15 INFO - 'download-and-extract', 04:20:15 INFO - 'populate-webroot', 04:20:15 INFO - 'create-virtualenv', 04:20:15 INFO - 'install', 04:20:15 INFO - 'setup-mitmproxy', 04:20:15 INFO - 'run-tests'), 04:20:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:20:15 INFO - 'disable_ccov_upload': False, 04:20:15 INFO - 'download_minidump_stackwalk': True, 04:20:15 INFO - 'download_symbols': 'ondemand', 04:20:15 INFO - 'enable_stylo': False, 04:20:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:20:15 INFO - 'tooltool.py': '/tools/tooltool.py', 04:20:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:20:15 INFO - '/tools/misc-python/virtualenv.py')}, 04:20:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:20:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:20:15 INFO - 'gecko_profile': False, 04:20:15 INFO - 'gecko_profile_interval': 0, 04:20:15 INFO - 'installer_path': 'installer.exe', 04:20:15 INFO - 'log_level': 'info', 04:20:15 INFO - 'log_name': 'talos', 04:20:15 INFO - 'log_to_console': True, 04:20:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:20:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:20:15 INFO - 'opt_config_files': (), 04:20:15 INFO - 'pip_index': False, 04:20:15 INFO - 'suite': 'dromaeojs-e10s', 04:20:15 INFO - 'system_bits': '32', 04:20:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:20:15 INFO - 'title': 'talos-linux64-ix-031', 04:20:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:20:15 INFO - 'use_talos_json': True, 04:20:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:20:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:20:15 INFO - 'work_dir': 'build'} 04:20:15 INFO - [mozharness: 2017-08-11 11:20:15.322400Z] Running clobber step. 04:20:15 INFO - Running pre-action listener: _resource_record_pre_action 04:20:15 INFO - Running main action method: clobber 04:20:15 INFO - rmtree: /builds/slave/test/build 04:20:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:20:16 INFO - Running post-action listener: _resource_record_post_action 04:20:16 INFO - [mozharness: 2017-08-11 11:20:16.340009Z] Finished clobber step (success) 04:20:16 INFO - [mozharness: 2017-08-11 11:20:16.340112Z] Running read-buildbot-config step. 04:20:16 INFO - Running pre-action listener: _resource_record_pre_action 04:20:16 INFO - Running main action method: read_buildbot_config 04:20:16 INFO - Using buildbot properties: 04:20:16 INFO - { 04:20:16 INFO - "project": "", 04:20:16 INFO - "product": "firefox", 04:20:16 INFO - "who": "archaeopteryx@coole-files.de", 04:20:16 INFO - "installer_path": "public/build/target.tar.bz2", 04:20:16 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 04:20:16 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 04:20:16 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 04:20:16 INFO - "stage_platform": "linux64-stylo", 04:20:16 INFO - "basedir": "/builds/slave/test", 04:20:16 INFO - "buildnumber": 22, 04:20:16 INFO - "platform": "ubuntu64_hw_stylo", 04:20:16 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 04:20:16 INFO - "slavebuilddir": "test", 04:20:16 INFO - "taskId": "NE7EWBC5ReOcwKoiomJhWg", 04:20:16 INFO - "branch": "mozilla-central", 04:20:16 INFO - "script_repo_revision": "production", 04:20:16 INFO - "revision": "65826179c86e2a3538bcffd969556937e15688cd", 04:20:16 INFO - "slavename": "talos-linux64-ix-031", 04:20:16 INFO - "repo_path": "mozilla-central" 04:20:16 INFO - } 04:20:16 INFO - Finding installer, test and symbols from parent task. 04:20:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NE7EWBC5ReOcwKoiomJhWg'}, attempt #1 04:20:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NE7EWBC5ReOcwKoiomJhWg'}, attempt #1 04:20:17 INFO - Task dependencies: B9z7C-sPS86eeKbhbIEBpQ 04:20:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B9z7C-sPS86eeKbhbIEBpQ'}, attempt #1 04:20:17 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/B9z7C-sPS86eeKbhbIEBpQ/artifacts/public/build/target.tar.bz2 04:20:17 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/B9z7C-sPS86eeKbhbIEBpQ/artifacts/public/build/target.test_packages.json 04:20:17 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/B9z7C-sPS86eeKbhbIEBpQ/artifacts/public/build/target.crashreporter-symbols.zip 04:20:17 INFO - Running post-action listener: _resource_record_post_action 04:20:17 INFO - [mozharness: 2017-08-11 11:20:17.746588Z] Finished read-buildbot-config step (success) 04:20:17 INFO - [mozharness: 2017-08-11 11:20:17.746810Z] Running download-and-extract step. 04:20:17 INFO - Running pre-action listener: _resource_record_pre_action 04:20:17 INFO - Running main action method: download_and_extract 04:20:17 INFO - mkdir: /builds/slave/test/build/tests 04:20:17 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')]} 04:20:17 INFO - trying https://queue.taskcluster.net/v1/task/B9z7C-sPS86eeKbhbIEBpQ/artifacts/public/build/target.test_packages.json 04:20:17 INFO - Downloading https://queue.taskcluster.net/v1/task/B9z7C-sPS86eeKbhbIEBpQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 04:20:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B9z7C-sPS86eeKbhbIEBpQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 04:20:18 INFO - Downloaded 989 bytes. 04:20:18 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 04:20:18 INFO - Using the following test package requirements: 04:20:18 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 04:20:18 INFO - u'common': [u'target.common.tests.zip'], 04:20:18 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 04:20:18 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 04:20:18 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 04:20:18 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 04:20:18 INFO - u'mozbase': [u'target.common.tests.zip'], 04:20:18 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 04:20:18 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 04:20:18 INFO - u'web-platform': [u'target.common.tests.zip', 04:20:18 INFO - u'target.web-platform.tests.tar.gz'], 04:20:18 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 04:20:18 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 04:20:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B9z7C-sPS86eeKbhbIEBpQ/artifacts/public/build/target.common.tests.zip 04:20:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B9z7C-sPS86eeKbhbIEBpQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:20:18 INFO - Fetch https://queue.taskcluster.net/v1/task/B9z7C-sPS86eeKbhbIEBpQ/artifacts/public/build/target.common.tests.zip into memory 04:20:19 INFO - Content-Length response header: 54036727 04:20:19 INFO - Bytes received: 54036727 04:20:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 04:20:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B9z7C-sPS86eeKbhbIEBpQ/artifacts/public/build/target.common.tests.zip 04:20:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B9z7C-sPS86eeKbhbIEBpQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:20:30 INFO - Fetch https://queue.taskcluster.net/v1/task/B9z7C-sPS86eeKbhbIEBpQ/artifacts/public/build/target.common.tests.zip into memory 04:20:31 INFO - Content-Length response header: 54036727 04:20:31 INFO - Bytes received: 54036727 04:20:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B9z7C-sPS86eeKbhbIEBpQ/artifacts/public/build/target.talos.tests.zip 04:20:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B9z7C-sPS86eeKbhbIEBpQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 04:20:43 INFO - Fetch https://queue.taskcluster.net/v1/task/B9z7C-sPS86eeKbhbIEBpQ/artifacts/public/build/target.talos.tests.zip into memory 04:20:44 INFO - Content-Length response header: 14496050 04:20:44 INFO - Bytes received: 14496050 04:20: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')]} 04:20:44 INFO - trying https://queue.taskcluster.net/v1/task/B9z7C-sPS86eeKbhbIEBpQ/artifacts/public/build/target.tar.bz2 04:20:44 INFO - Downloading https://queue.taskcluster.net/v1/task/B9z7C-sPS86eeKbhbIEBpQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 04:20:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B9z7C-sPS86eeKbhbIEBpQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 04:20:45 INFO - Downloaded 61834239 bytes. 04:20:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/B9z7C-sPS86eeKbhbIEBpQ/artifacts/public/build/target.tar.bz2 04:20:45 INFO - mkdir: /builds/slave/test/properties 04:20:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:20:45 INFO - Writing to file /builds/slave/test/properties/build_url 04:20:45 INFO - Contents: 04:20:45 INFO - build_url:https://queue.taskcluster.net/v1/task/B9z7C-sPS86eeKbhbIEBpQ/artifacts/public/build/target.tar.bz2 04:20:45 INFO - Running post-action listener: _resource_record_post_action 04:20:45 INFO - Running post-action listener: set_extra_try_arguments 04:20:45 INFO - [mozharness: 2017-08-11 11:20:45.391011Z] Finished download-and-extract step (success) 04:20:45 INFO - [mozharness: 2017-08-11 11:20:45.391109Z] Running populate-webroot step. 04:20:45 INFO - Running pre-action listener: _resource_record_pre_action 04:20:45 INFO - Running main action method: populate_webroot 04:20:45 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:20:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:20:45 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:20:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:20:45 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:20:45 INFO - 'tests': ['damp', 'tps']}, 04:20:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:20:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:20:45 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 04:20:45 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 04:20:45 INFO - 'other-e10s': {'tests': ['a11yr', 04:20:45 INFO - 'ts_paint', 04:20:45 INFO - 'tpaint', 04:20:45 INFO - 'sessionrestore', 04:20:45 INFO - 'sessionrestore_many_windows', 04:20:45 INFO - 'sessionrestore_no_auto_restore', 04:20:45 INFO - 'tabpaint']}, 04:20:45 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 04:20:45 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 04:20:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:20:45 INFO - 'tsvgr_opacity', 04:20:45 INFO - 'tart', 04:20:45 INFO - 'tscrollx', 04:20:45 INFO - 'tsvg_static']}, 04:20:45 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:20:45 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:20:45 INFO - 'talos_options': ['--mitmproxy', 04:20:45 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:20:45 INFO - '--firstNonBlankPaint'], 04:20:45 INFO - 'tests': ['tp6_google', 04:20:45 INFO - 'tp6_youtube', 04:20:45 INFO - 'tp6_amazon', 04:20:45 INFO - 'tp6_facebook']}, 04:20:45 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:20:45 INFO - 'talos_options': ['--stylo', 04:20:45 INFO - '--mitmproxy', 04:20:45 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:20:45 INFO - '--firstNonBlankPaint'], 04:20:45 INFO - 'tests': ['tp6_google', 04:20:45 INFO - 'tp6_youtube', 04:20:45 INFO - 'tp6_amazon', 04:20:45 INFO - 'tp6_facebook']}, 04:20:45 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:20:45 INFO - 'talos_options': ['--stylo', 04:20:45 INFO - '--stylo-threads=1', 04:20:45 INFO - '--mitmproxy', 04:20:45 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:20:45 INFO - '--firstNonBlankPaint'], 04:20:45 INFO - 'tests': ['tp6_google', 04:20:45 INFO - 'tp6_youtube', 04:20:45 INFO - 'tp6_amazon', 04:20:45 INFO - 'tp6_facebook']}, 04:20:45 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:20:45 INFO - 'talos_options': ['--xperf_path', 04:20:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:20:45 INFO - 'tests': ['tp5n']}}} 04:20:45 INFO - Running post-action listener: _resource_record_post_action 04:20:45 INFO - [mozharness: 2017-08-11 11:20:45.399570Z] Finished populate-webroot step (success) 04:20:45 INFO - [mozharness: 2017-08-11 11:20:45.399660Z] Running create-virtualenv step. 04:20:45 INFO - Running pre-action listener: _resource_record_pre_action 04:20:45 INFO - Running main action method: create_virtualenv 04:20:45 INFO - Creating virtualenv /builds/slave/test/build/venv 04:20:45 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 04:20:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:20:45 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 04:20:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:20:45 INFO - Using real prefix '/usr' 04:20:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:20:46 INFO - Installing distribute.............................................................................................................................................................................................done. 04:20:47 INFO - Installing pip.................done. 04:20:47 INFO - Return code: 0 04:20:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:20:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:20:48 INFO - Reading from file tmpfile_stdout 04:20:48 INFO - Output received: 04:20:48 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) 04:20:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:20:48 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')]} 04:20:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:48 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')]} 04:20:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x16ee1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18baa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a5bb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a5ef00>, '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 0x1a59ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a5a050>, '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': 'db0483c52d987ecb441a263b000001ad-1502449052.146338-740853330', '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 04:20:48 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 04:20:48 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 04:20:48 INFO - Using env: {'DISPLAY': ':0', 04:20:48 INFO - 'HOME': '/home/cltbld', 04:20:48 INFO - 'LANG': 'en_US.UTF-8', 04:20:48 INFO - 'LANGUAGE': 'en_US:en', 04:20:48 INFO - 'LOGNAME': 'cltbld', 04:20:48 INFO - 'MAIL': '/var/mail/cltbld', 04:20:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:48 INFO - 'MOZ_NO_REMOTE': '1', 04:20:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:20:48 INFO - 'NO_EM_RESTART': '1', 04:20:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:20:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:48 INFO - 'PWD': '/builds/slave/test', 04:20:48 INFO - 'SHELL': '/bin/bash', 04:20:48 INFO - 'SHLVL': '1', 04:20:48 INFO - 'TERM': 'linux', 04:20:48 INFO - 'TMOUT': '86400', 04:20:48 INFO - 'USER': 'cltbld', 04:20:48 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1502449052.146338-740853330', 04:20:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:48 INFO - '_': '/tools/buildbot/bin/python'} 04:20:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:48 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 04:20:48 INFO - Cleaning up... 04:20:48 INFO - Return code: 0 04:20:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:20:48 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')]} 04:20:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:48 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')]} 04:20:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x16ee1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18baa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a5bb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a5ef00>, '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 0x1a59ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a5a050>, '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': 'db0483c52d987ecb441a263b000001ad-1502449052.146338-740853330', '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 04:20:48 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 04:20:48 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 04:20:48 INFO - Using env: {'DISPLAY': ':0', 04:20:48 INFO - 'HOME': '/home/cltbld', 04:20:48 INFO - 'LANG': 'en_US.UTF-8', 04:20:48 INFO - 'LANGUAGE': 'en_US:en', 04:20:48 INFO - 'LOGNAME': 'cltbld', 04:20:48 INFO - 'MAIL': '/var/mail/cltbld', 04:20:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:48 INFO - 'MOZ_NO_REMOTE': '1', 04:20:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:20:48 INFO - 'NO_EM_RESTART': '1', 04:20:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:20:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:48 INFO - 'PWD': '/builds/slave/test', 04:20:48 INFO - 'SHELL': '/bin/bash', 04:20:48 INFO - 'SHLVL': '1', 04:20:48 INFO - 'TERM': 'linux', 04:20:48 INFO - 'TMOUT': '86400', 04:20:48 INFO - 'USER': 'cltbld', 04:20:48 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1502449052.146338-740853330', 04:20:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:48 INFO - '_': '/tools/buildbot/bin/python'} 04:20:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:48 INFO - Downloading/unpacking psutil>=3.1.1 04:20:48 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 04:20:48 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 04:20:48 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 04:20:48 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 04:20:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:20:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:20:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:20:49 INFO - Installing collected packages: psutil 04:20:49 INFO - Running setup.py install for psutil 04:20:49 INFO - building 'psutil._psutil_linux' extension 04:20:49 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 04:20:50 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 04:20:50 INFO - building 'psutil._psutil_posix' extension 04:20:50 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 04:20:50 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 04:20:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:20:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:20:50 INFO - Successfully installed psutil 04:20:50 INFO - Cleaning up... 04:20:50 INFO - Return code: 0 04:20:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:20:50 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')]} 04:20:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:50 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')]} 04:20:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:50 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 0x16ee1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18baa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a5bb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a5ef00>, '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 0x1a59ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a5a050>, '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': 'db0483c52d987ecb441a263b000001ad-1502449052.146338-740853330', '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 04:20:50 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 04:20:50 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 04:20:50 INFO - Using env: {'DISPLAY': ':0', 04:20:50 INFO - 'HOME': '/home/cltbld', 04:20:50 INFO - 'LANG': 'en_US.UTF-8', 04:20:50 INFO - 'LANGUAGE': 'en_US:en', 04:20:50 INFO - 'LOGNAME': 'cltbld', 04:20:50 INFO - 'MAIL': '/var/mail/cltbld', 04:20:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:50 INFO - 'MOZ_NO_REMOTE': '1', 04:20:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:20:50 INFO - 'NO_EM_RESTART': '1', 04:20:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:20:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:50 INFO - 'PWD': '/builds/slave/test', 04:20:50 INFO - 'SHELL': '/bin/bash', 04:20:50 INFO - 'SHLVL': '1', 04:20:50 INFO - 'TERM': 'linux', 04:20:50 INFO - 'TMOUT': '86400', 04:20:50 INFO - 'USER': 'cltbld', 04:20:50 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1502449052.146338-740853330', 04:20:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:50 INFO - '_': '/tools/buildbot/bin/python'} 04:20:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:20:50 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 04:20:50 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 04:20:50 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 04:20:50 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 04:20:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:20:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:20:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:20:51 INFO - Installing collected packages: mozsystemmonitor 04:20:51 INFO - Running setup.py install for mozsystemmonitor 04:20:51 INFO - Successfully installed mozsystemmonitor 04:20:51 INFO - Cleaning up... 04:20:51 INFO - Return code: 0 04:20:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:20:51 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')]} 04:20:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:51 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')]} 04:20:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x16ee1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18baa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a5bb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a5ef00>, '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 0x1a59ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a5a050>, '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': 'db0483c52d987ecb441a263b000001ad-1502449052.146338-740853330', '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 04:20:51 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 04:20:51 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 04:20:51 INFO - Using env: {'DISPLAY': ':0', 04:20:51 INFO - 'HOME': '/home/cltbld', 04:20:51 INFO - 'LANG': 'en_US.UTF-8', 04:20:51 INFO - 'LANGUAGE': 'en_US:en', 04:20:51 INFO - 'LOGNAME': 'cltbld', 04:20:51 INFO - 'MAIL': '/var/mail/cltbld', 04:20:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:51 INFO - 'MOZ_NO_REMOTE': '1', 04:20:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:20:51 INFO - 'NO_EM_RESTART': '1', 04:20:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:20:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:51 INFO - 'PWD': '/builds/slave/test', 04:20:51 INFO - 'SHELL': '/bin/bash', 04:20:51 INFO - 'SHLVL': '1', 04:20:51 INFO - 'TERM': 'linux', 04:20:51 INFO - 'TMOUT': '86400', 04:20:51 INFO - 'USER': 'cltbld', 04:20:51 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1502449052.146338-740853330', 04:20:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:51 INFO - '_': '/tools/buildbot/bin/python'} 04:20:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:52 INFO - Downloading/unpacking jsonschema==2.5.1 04:20:52 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 04:20:52 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 04:20:52 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 04:20:52 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 04:20:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:20:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:20:53 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 04:20:53 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 04:20:53 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 04:20:53 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 04:20:53 INFO - Downloading functools32-3.2.3-2.tar.gz 04:20:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:20:53 INFO - warning: no files found matching '*.txt' 04:20:53 INFO - no previously-included directories found matching 'build' 04:20:53 INFO - no previously-included directories found matching 'dist' 04:20:53 INFO - no previously-included directories found matching '.git*' 04:20:53 INFO - Installing collected packages: jsonschema, functools32 04:20:53 INFO - Running setup.py install for functools32 04:20:53 INFO - warning: no files found matching '*.txt' 04:20:53 INFO - no previously-included directories found matching 'build' 04:20:53 INFO - no previously-included directories found matching 'dist' 04:20:53 INFO - no previously-included directories found matching '.git*' 04:20:53 INFO - Successfully installed jsonschema functools32 04:20:53 INFO - Cleaning up... 04:20:53 INFO - Return code: 0 04:20:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:20:53 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')]} 04:20:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:53 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')]} 04:20:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:53 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 0x16ee1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18baa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a5bb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a5ef00>, '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 0x1a59ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a5a050>, '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': 'db0483c52d987ecb441a263b000001ad-1502449052.146338-740853330', '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 04:20:53 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 04:20:53 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 04:20:53 INFO - Using env: {'DISPLAY': ':0', 04:20:53 INFO - 'HOME': '/home/cltbld', 04:20:53 INFO - 'LANG': 'en_US.UTF-8', 04:20:53 INFO - 'LANGUAGE': 'en_US:en', 04:20:53 INFO - 'LOGNAME': 'cltbld', 04:20:53 INFO - 'MAIL': '/var/mail/cltbld', 04:20:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:53 INFO - 'MOZ_NO_REMOTE': '1', 04:20:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:20:53 INFO - 'NO_EM_RESTART': '1', 04:20:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:20:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:53 INFO - 'PWD': '/builds/slave/test', 04:20:53 INFO - 'SHELL': '/bin/bash', 04:20:53 INFO - 'SHLVL': '1', 04:20:53 INFO - 'TERM': 'linux', 04:20:53 INFO - 'TMOUT': '86400', 04:20:53 INFO - 'USER': 'cltbld', 04:20:53 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1502449052.146338-740853330', 04:20:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:53 INFO - '_': '/tools/buildbot/bin/python'} 04:20:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:20:53 INFO - Cleaning up... 04:20:53 INFO - Return code: 0 04:20:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:20:53 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')]} 04:20:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:53 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')]} 04:20:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:53 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 0x16ee1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18baa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a5bb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a5ef00>, '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 0x1a59ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a5a050>, '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': 'db0483c52d987ecb441a263b000001ad-1502449052.146338-740853330', '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 04:20:53 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 04:20:53 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 04:20:53 INFO - Using env: {'DISPLAY': ':0', 04:20:53 INFO - 'HOME': '/home/cltbld', 04:20:53 INFO - 'LANG': 'en_US.UTF-8', 04:20:53 INFO - 'LANGUAGE': 'en_US:en', 04:20:53 INFO - 'LOGNAME': 'cltbld', 04:20:53 INFO - 'MAIL': '/var/mail/cltbld', 04:20:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:53 INFO - 'MOZ_NO_REMOTE': '1', 04:20:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:20:53 INFO - 'NO_EM_RESTART': '1', 04:20:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:20:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:53 INFO - 'PWD': '/builds/slave/test', 04:20:53 INFO - 'SHELL': '/bin/bash', 04:20:53 INFO - 'SHLVL': '1', 04:20:53 INFO - 'TERM': 'linux', 04:20:53 INFO - 'TMOUT': '86400', 04:20:53 INFO - 'USER': 'cltbld', 04:20:53 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1502449052.146338-740853330', 04:20:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:53 INFO - '_': '/tools/buildbot/bin/python'} 04:20:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:53 INFO - Downloading/unpacking blobuploader==1.2.4 04:20:53 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 04:20:53 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 04:20:53 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 04:20:53 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 04:20:55 INFO - Downloading blobuploader-1.2.4.tar.gz 04:20:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:20:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:20:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:55 INFO - Downloading docopt-0.6.1.tar.gz 04:20:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:20:55 INFO - Installing collected packages: blobuploader, requests, docopt 04:20:55 INFO - Running setup.py install for blobuploader 04:20:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:20:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:20:55 INFO - Running setup.py install for requests 04:20:56 INFO - Running setup.py install for docopt 04:20:56 INFO - Successfully installed blobuploader requests docopt 04:20:56 INFO - Cleaning up... 04:20:56 INFO - Return code: 0 04:20:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:20:56 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')]} 04:20:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:56 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')]} 04:20:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:56 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 0x16ee1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18baa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a5bb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a5ef00>, '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 0x1a59ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a5a050>, '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': 'db0483c52d987ecb441a263b000001ad-1502449052.146338-740853330', '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 04:20:56 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 04:20:56 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 04:20:56 INFO - Using env: {'DISPLAY': ':0', 04:20:56 INFO - 'HOME': '/home/cltbld', 04:20:56 INFO - 'LANG': 'en_US.UTF-8', 04:20:56 INFO - 'LANGUAGE': 'en_US:en', 04:20:56 INFO - 'LOGNAME': 'cltbld', 04:20:56 INFO - 'MAIL': '/var/mail/cltbld', 04:20:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:56 INFO - 'MOZ_NO_REMOTE': '1', 04:20:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:20:56 INFO - 'NO_EM_RESTART': '1', 04:20:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:20:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:56 INFO - 'PWD': '/builds/slave/test', 04:20:56 INFO - 'SHELL': '/bin/bash', 04:20:56 INFO - 'SHLVL': '1', 04:20:56 INFO - 'TERM': 'linux', 04:20:56 INFO - 'TMOUT': '86400', 04:20:56 INFO - 'USER': 'cltbld', 04:20:56 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1502449052.146338-740853330', 04:20:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:56 INFO - '_': '/tools/buildbot/bin/python'} 04:20:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:20:56 INFO - Running setup.py (path:/tmp/pip-PS8OTe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:20:56 INFO - Running setup.py (path:/tmp/pip-TZZyzA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:20:56 INFO - Running setup.py (path:/tmp/pip-y4PMO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:20:56 INFO - Running setup.py (path:/tmp/pip-hyW8oB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:20:56 INFO - Running setup.py (path:/tmp/pip-jLgZuM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:20:56 INFO - Running setup.py (path:/tmp/pip-ZNV9lD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:20:56 INFO - Running setup.py (path:/tmp/pip-8gBPqu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:20:56 INFO - Running setup.py (path:/tmp/pip-NlGqKW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:20:57 INFO - Running setup.py (path:/tmp/pip-BUhwd7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:20:57 INFO - Running setup.py (path:/tmp/pip-9npkR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:20:57 INFO - Running setup.py (path:/tmp/pip-SJEJpT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:20:57 INFO - Running setup.py (path:/tmp/pip-qBwOIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:20:57 INFO - Running setup.py (path:/tmp/pip-WAYcPB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:20:57 INFO - Running setup.py (path:/tmp/pip-0MIKrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:20:57 INFO - Running setup.py (path:/tmp/pip-kS_bC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:20:57 INFO - Running setup.py (path:/tmp/pip-FAx1Kd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:20:57 INFO - Running setup.py (path:/tmp/pip-gWFX8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:20:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:20:57 INFO - Running setup.py install for manifestparser 04:20:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:20:57 INFO - Running setup.py install for mozcrash 04:20:57 INFO - Running setup.py install for mozdebug 04:20:58 INFO - Running setup.py install for mozdevice 04:20:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:20:58 INFO - Running setup.py install for mozfile 04:20:58 INFO - Running setup.py install for mozhttpd 04:20:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:20:58 INFO - Running setup.py install for mozinfo 04:20:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:20:58 INFO - Running setup.py install for mozInstall 04:20:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:20:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:20:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:20:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:20:58 INFO - Running setup.py install for mozleak 04:20:58 INFO - Running setup.py install for mozlog 04:20:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:20:58 INFO - Running setup.py install for moznetwork 04:20:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:20:58 INFO - Running setup.py install for mozprocess 04:20:58 INFO - Running setup.py install for mozprofile 04:20:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:20:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:20:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:20:59 INFO - Running setup.py install for mozrunner 04:20:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:20:59 INFO - Running setup.py install for mozscreenshot 04:20:59 INFO - Running setup.py install for moztest 04:20:59 INFO - Running setup.py install for mozversion 04:20:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:20:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:20:59 INFO - Cleaning up... 04:20:59 INFO - Return code: 0 04:20:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:20:59 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')]} 04:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:59 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')]} 04:20:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:59 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 0x16ee1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18baa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a5bb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a5ef00>, '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 0x1a59ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a5a050>, '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': 'db0483c52d987ecb441a263b000001ad-1502449052.146338-740853330', '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 04:20:59 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 04:20:59 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 04:20:59 INFO - Using env: {'DISPLAY': ':0', 04:20:59 INFO - 'HOME': '/home/cltbld', 04:20:59 INFO - 'LANG': 'en_US.UTF-8', 04:20:59 INFO - 'LANGUAGE': 'en_US:en', 04:20:59 INFO - 'LOGNAME': 'cltbld', 04:20:59 INFO - 'MAIL': '/var/mail/cltbld', 04:20:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:59 INFO - 'MOZ_NO_REMOTE': '1', 04:20:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:20:59 INFO - 'NO_EM_RESTART': '1', 04:20:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:20:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:59 INFO - 'PWD': '/builds/slave/test', 04:20:59 INFO - 'SHELL': '/bin/bash', 04:20:59 INFO - 'SHLVL': '1', 04:20:59 INFO - 'TERM': 'linux', 04:20:59 INFO - 'TMOUT': '86400', 04:20:59 INFO - 'USER': 'cltbld', 04:20:59 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1502449052.146338-740853330', 04:20:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:59 INFO - '_': '/tools/buildbot/bin/python'} 04:20:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:20:59 INFO - Running setup.py (path:/tmp/pip-QxTLzf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:20:59 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)) 04:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:20:59 INFO - Running setup.py (path:/tmp/pip-19tBuN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:20:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:20:59 INFO - Running setup.py (path:/tmp/pip-aoTUNB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:20:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:20:59 INFO - Running setup.py (path:/tmp/pip-Ro3gFO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:21:00 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)) 04:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:21:00 INFO - Running setup.py (path:/tmp/pip-N6AiNn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:21:00 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)) 04:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:21:00 INFO - Running setup.py (path:/tmp/pip-aNku8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:21:00 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)) 04:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:21:00 INFO - Running setup.py (path:/tmp/pip-mjBC4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:21:00 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)) 04:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:21:00 INFO - Running setup.py (path:/tmp/pip-qeK5kD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:21:00 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)) 04:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:21:00 INFO - Running setup.py (path:/tmp/pip-Xj4sK7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:21:00 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)) 04:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:21:00 INFO - Running setup.py (path:/tmp/pip-VqSiYC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:21:00 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)) 04:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:21:00 INFO - Running setup.py (path:/tmp/pip-AcBB7H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:21:00 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)) 04:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:21:00 INFO - Running setup.py (path:/tmp/pip-dwvJlE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:21:00 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)) 04:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:21:00 INFO - Running setup.py (path:/tmp/pip-jd0cdd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:21:00 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)) 04:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:21:00 INFO - Running setup.py (path:/tmp/pip-jUVRNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:21:00 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)) 04:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:21:00 INFO - Running setup.py (path:/tmp/pip-wa5Nvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:21:00 INFO - Running setup.py (path:/tmp/pip-m3nEAV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:21:00 INFO - Running setup.py (path:/tmp/pip-Ve0d35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:21:00 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)) 04:21:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:02 INFO - Downloading blessings-1.6.tar.gz 04:21:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:21:02 INFO - Installing collected packages: blessings 04:21:02 INFO - Running setup.py install for blessings 04:21:02 INFO - Successfully installed blessings 04:21:02 INFO - Cleaning up... 04:21:02 INFO - Return code: 0 04:21:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:21:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:21:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:21:02 INFO - Reading from file tmpfile_stdout 04:21:02 INFO - Current package versions: 04:21:02 INFO - argparse == 1.2.1 04:21:02 INFO - blessings == 1.6 04:21:02 INFO - blobuploader == 1.2.4 04:21:02 INFO - docopt == 0.6.1 04:21:02 INFO - functools32 == 3.2.3-2 04:21:02 INFO - jsonschema == 2.5.1 04:21:02 INFO - manifestparser == 1.1 04:21:02 INFO - mozInstall == 1.13 04:21:02 INFO - mozcrash == 1.0 04:21:02 INFO - mozdebug == 0.1 04:21:02 INFO - mozdevice == 0.51 04:21:02 INFO - mozfile == 1.2 04:21:02 INFO - mozhttpd == 0.7 04:21:02 INFO - mozinfo == 0.10 04:21:02 INFO - mozleak == 0.1 04:21:02 INFO - mozlog == 3.5 04:21:02 INFO - moznetwork == 0.27 04:21:02 INFO - mozprocess == 0.25 04:21:02 INFO - mozprofile == 0.28 04:21:02 INFO - mozrunner == 6.13 04:21:02 INFO - mozscreenshot == 0.1 04:21:02 INFO - mozsystemmonitor == 0.3 04:21:02 INFO - moztest == 0.8 04:21:02 INFO - mozversion == 1.4 04:21:02 INFO - psutil == 3.1.1 04:21:02 INFO - requests == 1.2.3 04:21:02 INFO - wsgiref == 0.1.2 04:21:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:21:02 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')]} 04:21:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:02 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')]} 04:21:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x16ee1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18baa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a5bb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a5ef00>, '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 0x1a59ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a5a050>, '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': 'db0483c52d987ecb441a263b000001ad-1502449052.146338-740853330', '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 04:21:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:21:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:21:02 INFO - Using env: {'DISPLAY': ':0', 04:21:02 INFO - 'HOME': '/home/cltbld', 04:21:02 INFO - 'LANG': 'en_US.UTF-8', 04:21:02 INFO - 'LANGUAGE': 'en_US:en', 04:21:02 INFO - 'LOGNAME': 'cltbld', 04:21:02 INFO - 'MAIL': '/var/mail/cltbld', 04:21:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:02 INFO - 'MOZ_NO_REMOTE': '1', 04:21:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:21:02 INFO - 'NO_EM_RESTART': '1', 04:21:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:21:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:02 INFO - 'PWD': '/builds/slave/test', 04:21:02 INFO - 'SHELL': '/bin/bash', 04:21:02 INFO - 'SHLVL': '1', 04:21:02 INFO - 'TERM': 'linux', 04:21:02 INFO - 'TMOUT': '86400', 04:21:02 INFO - 'USER': 'cltbld', 04:21:02 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1502449052.146338-740853330', 04:21:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:02 INFO - '_': '/tools/buildbot/bin/python'} 04:21:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:21:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:21:02 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 04:21:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 04:21:03 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)) 04:21:03 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)) 04:21:03 INFO - Installing collected packages: simplejson 04:21:03 INFO - Running setup.py install for simplejson 04:21:04 INFO - building 'simplejson._speedups' extension 04:21:04 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 04:21:04 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 04:21:04 INFO - Successfully installed simplejson 04:21:04 INFO - Cleaning up... 04:21:04 INFO - Return code: 0 04:21:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:21:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:04 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 0x16ee1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18baa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a5bb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a5ef00>, '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 0x1a59ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a5a050>, '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': 'db0483c52d987ecb441a263b000001ad-1502449052.146338-740853330', '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 04:21:04 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 04:21:04 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 04:21:04 INFO - Using env: {'DISPLAY': ':0', 04:21:04 INFO - 'HOME': '/home/cltbld', 04:21:04 INFO - 'LANG': 'en_US.UTF-8', 04:21:04 INFO - 'LANGUAGE': 'en_US:en', 04:21:04 INFO - 'LOGNAME': 'cltbld', 04:21:04 INFO - 'MAIL': '/var/mail/cltbld', 04:21:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:04 INFO - 'MOZ_NO_REMOTE': '1', 04:21:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:21:04 INFO - 'NO_EM_RESTART': '1', 04:21:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:21:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:04 INFO - 'PWD': '/builds/slave/test', 04:21:04 INFO - 'SHELL': '/bin/bash', 04:21:04 INFO - 'SHLVL': '1', 04:21:04 INFO - 'TERM': 'linux', 04:21:04 INFO - 'TMOUT': '86400', 04:21:04 INFO - 'USER': 'cltbld', 04:21:04 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1502449052.146338-740853330', 04:21:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:04 INFO - '_': '/tools/buildbot/bin/python'} 04:21:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:21:05 INFO - Cleaning up... 04:21:05 INFO - Return code: 0 04:21:05 INFO - Running post-action listener: _resource_record_post_action 04:21:05 INFO - Running post-action listener: _start_resource_monitoring 04:21:05 INFO - Starting resource monitoring. 04:21:05 INFO - [mozharness: 2017-08-11 11:21:05.256249Z] Finished create-virtualenv step (success) 04:21:05 INFO - [mozharness: 2017-08-11 11:21:05.256770Z] Running install step. 04:21:05 INFO - Running pre-action listener: _resource_record_pre_action 04:21:05 INFO - Running main action method: install 04:21:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:21:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:21:05 INFO - Reading from file tmpfile_stdout 04:21:05 INFO - Detecting whether we're running mozinstall >=1.0... 04:21:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:21:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:21:05 INFO - Reading from file tmpfile_stdout 04:21:05 INFO - Output received: 04:21:05 INFO - Usage: mozinstall [options] installer 04:21:05 INFO - Options: 04:21:05 INFO - -h, --help show this help message and exit 04:21:05 INFO - -d DEST, --destination=DEST 04:21:05 INFO - Directory to install application into. [default: 04:21:05 INFO - "/builds/slave/test"] 04:21:05 INFO - --app=APP Application being installed. [default: firefox] 04:21:05 INFO - mkdir: /builds/slave/test/build/application 04:21:05 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'] 04:21:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 04:21:23 INFO - Reading from file tmpfile_stdout 04:21:23 INFO - Output received: 04:21:23 INFO - /builds/slave/test/build/application/firefox/firefox 04:21:23 INFO - Running post-action listener: _resource_record_post_action 04:21:23 INFO - [mozharness: 2017-08-11 11:21:23.517581Z] Finished install step (success) 04:21:23 INFO - [mozharness: 2017-08-11 11:21:23.517869Z] Running setup-mitmproxy step. 04:21:23 INFO - Running pre-action listener: _resource_record_pre_action 04:21:23 INFO - Running main action method: setup_mitmproxy 04:21:23 INFO - Skipping: mitmproxy is not required 04:21:23 INFO - Running post-action listener: _resource_record_post_action 04:21:23 INFO - [mozharness: 2017-08-11 11:21:23.519019Z] Finished setup-mitmproxy step (success) 04:21:23 INFO - [mozharness: 2017-08-11 11:21:23.519240Z] Running run-tests step. 04:21:23 INFO - Running pre-action listener: _resource_record_pre_action 04:21:23 INFO - Running pre-action listener: _set_gcov_prefix 04:21:23 INFO - Running main action method: run_tests 04:21:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:21:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:21:23 INFO - Python 2.7.3 04:21:23 INFO - Return code: 0 04:21:23 INFO - grabbing minidump binary from tooltool 04:21:23 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')]} 04:21:23 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 0x1a5ef00>, '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 0x1a59ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a5a050>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:21:23 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 04:21:23 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 04:21:23 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 04:21:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:21:23 INFO - Return code: 0 04:21:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:21:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:21:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:21:23 INFO - ENV: RUST_BACKTRACE is now full 04:21:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:21:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:21:23 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/B9z7C-sPS86eeKbhbIEBpQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 04:21:23 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/B9z7C-sPS86eeKbhbIEBpQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-031 --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 04:21:23 INFO - Using env: {'DISPLAY': ':0', 04:21:23 INFO - 'HOME': '/home/cltbld', 04:21:23 INFO - 'LANG': 'en_US.UTF-8', 04:21:23 INFO - 'LANGUAGE': 'en_US:en', 04:21:23 INFO - 'LOGNAME': 'cltbld', 04:21:23 INFO - 'MAIL': '/var/mail/cltbld', 04:21:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:21:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:21:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:23 INFO - 'MOZ_NO_REMOTE': '1', 04:21:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:21:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:21:23 INFO - 'NO_EM_RESTART': '1', 04:21:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:21:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:23 INFO - 'PWD': '/builds/slave/test', 04:21:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:21:23 INFO - 'RUST_BACKTRACE': 'full', 04:21:23 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:21:23 INFO - 'SHELL': '/bin/bash', 04:21:23 INFO - 'SHLVL': '1', 04:21:23 INFO - 'STYLO_FORCE_ENABLED': '1', 04:21:23 INFO - 'TERM': 'linux', 04:21:23 INFO - 'TMOUT': '86400', 04:21:23 INFO - 'USER': 'cltbld', 04:21:23 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1502449052.146338-740853330', 04:21:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:23 INFO - '_': '/tools/buildbot/bin/python'} 04:21:23 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/B9z7C-sPS86eeKbhbIEBpQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 04:21:23 INFO - mozversion application_buildid: 20170811103911 04:21:23 INFO - mozversion application_changeset: 65826179c86e2a3538bcffd969556937e15688cd 04:21:23 INFO - mozversion application_display_name: Nightly 04:21:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:21:23 INFO - mozversion application_name: Firefox 04:21:23 INFO - mozversion application_remotingname: firefox 04:21:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:21:23 INFO - mozversion application_vendor: Mozilla 04:21:23 INFO - mozversion application_version: 57.0a1 04:21:23 INFO - mozversion platform_buildid: 20170811103911 04:21:23 INFO - mozversion platform_changeset: 65826179c86e2a3538bcffd969556937e15688cd 04:21:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:21:23 INFO - mozversion platform_version: 57.0a1 04:21:23 INFO - using testdate: 1502450483 04:21:23 INFO - actual date: 1502450483 04:21:23 INFO - starting webserver on 'localhost:33489' 04:21:23 INFO - SUITE-START | Running 2 tests 04:21:23 INFO - TEST-START | dromaeo_css 04:21:23 INFO - Initialising browser for dromaeo_css test... 04:21:23 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33489/getInfo.html -profile /tmp/tmpNiTht4/profile 04:21:23 INFO - TEST-INFO | started process 19708 (/builds/slave/test/build/application/firefox/firefox http://localhost:33489/getInfo.html) 04:21:29 INFO - TEST-INFO | 19708: exit 0 04:21:29 INFO - Browser initialized. 04:21:29 INFO - Running cycle 1/1 for dromaeo_css test... 04:21:29 INFO - TEST-INFO | started process 19946 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNiTht4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:21:30 INFO - PID 19946 | 04:21:30 INFO - PID 19946 | (/builds/slave/test/build/application/firefox/firefox:20000): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:21:30 INFO - PID 19946 | 04:21:41 INFO - PID 19946 | RSS: Main: 171393024 04:21:41 INFO - PID 19946 | 04:24:41 INFO - PID 19946 | Cycle 1(1): loaded http://localhost:33489/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33489/tests/dromaeo/cssquery-ext.html) 04:24:42 INFO - PID 19946 | RSS: Main: 176672768 04:24:42 INFO - PID 19946 | 04:27:43 INFO - PID 19946 | Cycle 1(1): loaded http://localhost:33489/tests/dromaeo/cssquery-ext.html (next: http://localhost:33489/tests/dromaeo/cssquery-jquery.html) 04:27:44 INFO - PID 19946 | RSS: Main: 173486080 04:27:44 INFO - PID 19946 | 04:30:46 INFO - PID 19946 | Cycle 1(1): loaded http://localhost:33489/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33489/tests/dromaeo/cssquery-mootools.html) 04:30:46 INFO - PID 19946 | RSS: Main: 175849472 04:30:46 INFO - PID 19946 | 04:33:48 INFO - PID 19946 | Cycle 1(1): loaded http://localhost:33489/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33489/tests/dromaeo/cssquery-prototype.html) 04:33:49 INFO - PID 19946 | RSS: Main: 173723648 04:33:49 INFO - PID 19946 | 04:36:52 INFO - PID 19946 | Cycle 1(1): loaded http://localhost:33489/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33489/tests/dromaeo/cssquery-yui.html) 04:36:53 INFO - PID 19946 | RSS: Main: 177401856 04:36:53 INFO - PID 19946 | 04:39:55 INFO - PID 19946 | Cycle 1(1): loaded http://localhost:33489/tests/dromaeo/cssquery-yui.html (next: http://localhost:33489/tests/dromaeo/cssquery-dojo.html) 04:39:55 INFO - PID 19946 | RSS: Main: 177270784 04:39:55 INFO - PID 19946 | 04:39:55 INFO - PID 19946 | __start_tp_report 04:39:55 INFO - PID 19946 | _x_x_mozilla_page_load 04:39:55 INFO - PID 19946 | _x_x_mozilla_page_load_details 04:39:55 INFO - PID 19946 | |i|pagename|runs| 04:39:55 INFO - PID 19946 | |0;dojo.html;3139.544766008929;3174.936501269976;3194.217416732901;3204.1028512016637;3204.182933351995;6157.815265542035;6309.305976342599;6341.334159913212;6369.840753981147;6377.04344348348;6127.7855275065385;6229.532785041118;6241.21984751906;6244.219472565929;6266.749330026793;5611.158326251075;5675.829725108243;5694.4590263924865;5697.857553561153;5729.312482502106;5710.200571919934;5736.225609542721;5738.770449182049;5743.253377060976;5797.333306669724;5696.231008813819;5709.400512946164;5726.656400615955;5793.33376661683;5800.680962547058;5170.06122703986;5516.282883225195;5649.887002259932;5721.4850663440075;5734.311882574076;5597.412271711452;5636.323641163087;5667.829965101054;5687.488126068673;5699.629524080921;6136.202293701835;6200.62796232223;6218.00511918091;6226.40849119333;6239.500839932794;6679.666016699194;6704.094947182117;6723.899141512881;6747.493937954673;6773.356531129535;6788.049673045777;6792.0151578021405;6858.965705171492;6884.724611015554;6888.724451022003;6251.78118765841;6260.937390626126;6349.555531112824;6353.777617783356;6355.046742988588;6840.657967101625;6933.826654333581;7008.229094799568;7015.228324884259;7022;6473.2232132144445;6556.377144171296;6565.606063636197;6655.900161497581;6664.400203981665;6691.063251144844;6744.62904540255;6751.932480675228;6805.863882722417;6812.046313516112;6717.261101278856;6723.966380168068;6782.457403407716;6815.045893574904;6821.829454263584;6582.605043697394;6741.831454213684;6759.155105611799;6832.863342733118;6839;5408.729563521808;5455.290812194389;5501.284832971688;5512.834614961557;5521.834344969577;4691.366665500113;4691.55430234134;4710.62315014798;4739.715617062919;4775.355327030876;5803.216565763652;5804.970975145097;5807.796727114531;5873.735681894325;5890.087036509348;6681.699323530453;6710.127683401127;6733.730650774012;6773.796786096425;6857.417119544894;5826.126081087786;5826.126081087786;5839.948809214297;5842.182094506772;5843.620164689281;5858.384869588717;5948.881022379528;5957.434043765836;5976.193213916153;6010.579259451883;6492.09110724499;6503.317151699003;6522.249941256721;6597.901031484627;6612;6678.2320033194865;6722.831929201809;6797.626130562867;6804.523683342119;6815.727370905307;6471.902921456276;6476.481881449567;6480.935190648033;6490.8052758417325;6503.25212600557;6568.441682457044;6615.735370585123;6638;6656.301088385843;6661.333866613494;6835.14560679915;6879.931200687929;6883.070785444102;6885.311468853075;6904.516683832084;6557.147570815763;6565.113709649328;6664.266930637723;6669.1330127083165;6669.299723529154;6433.613983161025;6528;6551.6069035858;6552.312007239362;6660.600363977983;6506.121673574196;6517.771877984248;6520.478361731145;6523.11937888379;6526.314736952741;6578.572392794453;6591.34086591337;6650.334966503505;6686.231083425274;6711.261761206166;6331.335209803088;6385.489160867212;6435.646039467687;6437.227532696106;6441.806745797634 04:39:55 INFO - PID 19946 | |1;ext.html;837.5309826497162;873.8907636545433;901.3690416708304;905.5381755304793;1196.6709154982384;31664.208394790112;32136;41908.79045604772;42983;43153;44369.778151109196;44663.553364466425;44730.776346118306;44903;44960;7411.48119631626;7846.979892613981;8043.678252869879;8045.275925166734;8056.8388632227325;7917.168697286788;7960.203979602023;7974.401919856005;8005.279524842763;8039.276465118138;7915.643796029192;7989.161138080505;7992.920070799305;7997.320227780646;7998;7899.763007109796;7967.44227904047;7968.8804667929735;7971.163027882076;7986.2013798620255;7885.566293853835;7974.122846486882;7977.401694872908;7978.162292959214;7980.162082981262;39394.409083863764;39587.40618890719;39625.80187099075;39648.20703585912;39669.40495892564;3318.900432987014;3359.395308844407;3360.647132051148;3373.544571482843;3375.476801095834;32162.517562236586;32309.83845080783;32347;32366;32395.352092958245;5067.670601410897;5073.1122053640465;5081.822136225252;5087.872803179913;5096.490350964911;199261;200789;201179;201276;201630;50902.41878230977;50938;51054.489455105715;51074.23388649173;51229.7438512805;53227.467725323026;53296.46703532915;53379;53387.19919201215;53487.46512534825;15198;17201.7419738704;17478.03870787119;17539;17542.736858947126;12901.806472902914;13987.650308742363;14367;14375.496857609933;14402.927985360217;14396.496122635866;14528.709425811636;14584.781228281585;14586.197759123137;14683.339249733788;5727.570432217601;5770.798022069207;5803.361630220672;5833.241678581842;5849.970750146222;1018.5467466977273;1019.1388276906002;1039.0388890276463;1039.4231201682987;1043.9321444106108;23280.65079023816;23368.298951031495;23377.532449350918;23464;23579;1136.176272202663;1141.3494308244387;1143.988560114405;1149.942053310956;1154.342025045716;12490.375481225903;16129.838701612834;16418.589535261475;16465.506034818976;16477.34090636361;240193;242810;242823;243063;243170;60552.69723651354;61149.38850611437;61164.69417652972;61222;61252.69373653103;1490.4410845932775;1499.580117567061;1527.9694406111817;1527.9847201527843;1532.4712974023944;27675.86162069177;27722.861385692944;28253.15240542787;28717.13848584546;28972;40182.397264040475;40194;40354.39468408035;40406.79796601116;40412.3938140934;211241;214463;215230;215241;215313;36214.4567831488;36606;38684.41973370346;38841.417378739905;38883.416748749354;47882.5211747892;48411;48711.512884870695;48802.511974879795;48916;35489.290214195054;35540.289194216486;36009.639903600626;36100.458493122096;36657.26685466329;34924.825375872955;34979.82510087535;35074;35361.64638353584;35401.74187019204;38581;38757;38801.805990969864;38977.61022389853;39043.609563904;34031.14922126967;34071.148221294665;34458.310833782685;34699.65300346964;34796.47805282872;23910.880445598355;25067.498650026533;25290.241292761246;25543.361415964006;25589.872050639628 04:39:55 INFO - PID 19946 | |2;jquery.html;563.6589863132805;583.8569550460138;587.3979171349368;597.3697748874937;612.3111499562991;22656.88671556642;23782.810859457044;23881.76118238817;23990.040398384044;24512.877435612798;25692;26416.471670566625;26452.603210951853;26464.338391540223;26481.205563833068;6533.934660653392;6665.233498147702;6688.331166883321;6692.9330706693045;6709.798706038802;5901.645901245917;6173.228346456693;6417.39034791694;6439.22729272488;6441.806745797634;6392.584482008655;6393.136926514931;6400.807975760711;6404.743810247608;6405.007223880283;6385.042243663464;6399.584027038274;6400.487960963112;6401.519886008545;6404.167458230447;5974.551908606828;5986.491148252404;5988.730507127189;5991.91012134814;6000.789972350991;30020;30559.541606875915;30996.690033099607;31449.842750786098;31475.685243147505;1929.604431091623;1964.8919309437974;1966.803319668036;1975.7629084509952;1976.456474469518;19252.4224273272;19490.90254548718;19605;20500.897495512578;20632.071556779832;2426.344886880532;2450.9509809803703;2480.6403071554705;2504.4615407687434;2509.04656230633;140439.29780351135;145191;147579;147705;147793;14226.85773142276;14978.625534361729;15021.324040418207;15121.39514419411;15430.38278468871;23976.40058998504;24071.555706657655;24130.396740081287;24157;24171.395715107265;10376.118029967538;10388.272820902464;10466.895331046744;10534.947325263478;10544.841827372595;8875.73372798817;9728.95135524332;9759.365641233297;9913.107820296063;10157.441340726331;8322.20939010793;9167;9465.52672366379;9520.905400811811;9542.52287385628;2715.827338129528;2755.3249453883927;2822.252103192656;2869.483492971285;2924.6344206974127;943.834828904939;962.3744566032135;970.8592254123113;976.7460460280379;984.4388698441819;19117.330893418664;19601.1179496923;19881.900590496956;19990.800091998895;23292.41768955756;1013.6806905824642;1042.0985847242082;1062.0600768319937;1066.4294602387686;1070.7751372211767;11012.834807477895;11499.570352311192;11607.245529040585;11667.11640132326;13324.666883327995;475654;479032;489432;490580;492634;118076;118804;119592;119718;121381.39309303397;1258.930758808256;1274.3819247664876;1316.993415032938;1318.7626227279181;1319.6107148391247;20359;20588.86761228147;20815.063322150236;21013.579728405653;21052.894735526224;55006.44993550013;56178.43821561732;56355.436445635016;57795;58615.4138458627;72262.63868680834;72631.63684181757;73905;74001.6299918497;74400;12063.939680301542;13924.955628327712;14253.28733563297;14662.926685366505;14973.326200320795;22558.887205563868;23130.537389251785;23256.76743232546;24694.876525617256;24800.25599232026;18643.161057752164;19012.714809277597;19014.524636884187;19021.23915043438;19175.32886348971;17264.223109960334;17472;17558;17722.822771772633;18064.187111579748;19682.21271149138;19814;19969.500762481053;20154.093065812365;20166;14665.340059697553;14803.96372253975;14809.407623694931;14825.629359266104;14897.925510372379;18720.812791872453;18768.061596919826;18852.905735471235;18953.43139705811;19044.666873318736 04:39:55 INFO - PID 19946 | |3;mootools.html;1348.4134401535332;1382.1223523062858;1403.8104855844465;1412.4773833681536;1414.8231471066117;14333.140011599311;14546.127232366065;14850.25748712564;14919.32863021164;14923.403063877446;15043.473478428257;15273.694526109472;15296.847031529682;15312.46406375777;15323.157226352547;2575.5621544337414;2735.1521028481134;2797.1748334241347;2797.9860100699466;2798.9020384286555;2746.148693904896;2769.2661444717064;2772.487089888377;2773.514634938888;2775.278427608826;2744.656917885264;2751.683556391021;2759.84820834855;2776.3891943772337;2780.06867699321;2646.2193652872447;2653.7744291735225;2667.119850449357;2667.9599806002925;2724.6730392352847;2612.8954841806303;2632.1313966391044;2646.8808903599192;2649.748273913975;2652.562327216007;14526.854731452657;14541.200233987127;14683.632909177357;14743.041702289316;14753.631159220999;7725.072991241031;7961.1242763295995;8009.95995020027;8035.116137224901;8039.995000624922;5041.470645582198;5145.279660847485;5238.947610523884;5282.735863206863;5297.020051290485;1866.953326166843;1870.4014715290975;1872.7659042619673;1913.1104036622899;1915.9616807663908;22050;22219.22232721863;22229.555408891894;24478.388040299134;25753.227403177934;6683.866322673519;6699.899501507482;6779.830504237335;7024.367806897404;7042.366187043191;16405;16422.09678467672;16436.6712665746;16475.258613362428;16511.752323715154;7777.72777952769;7854.410919181084;7875.684972601146;7885.093214280431;7894.131645518988;7197.208307086216;7293.598852063187;7331.083614548182;7334.376577990931;7359.264073592598;5013.623978201649;5199.220116982483;5199.636025478256;5212.661177023557;5238.319018527567;1551.7129331073788;1554.0830909763295;1556.9299381527858;1559.9766003509956;1562.2735428025794;848.440029580474;864.7405778266495;876.1019954546514;885.106042896679;890.2299510923134;7016.122984626922;7046.753363632249;7951.284384405431;8545.529995850164;8564.785880352916;985.0002247718571;1015.8222311095528;1018.6282007067367;1021.6985989133224;1024.3393011507685;5122.231665250242;5153.793848246103;5192.273081768555;5299.761510732026;5370.9731451342495;19351.612967740566;19654.115564799617;20102.39692809218;20337.49156271105;21106.05022773979;20676.793232067786;20738.792612073987;20904.47738806542;20915;21053.684194737092;10492.580296788043;11221.495032723547;11226.270292430965;11344.773104537862;11464.140189485823;8105.594720264081;8414.495130292202;8624.396292259482;8837;8986.236169925629;17729.734053989458;17944.730829037835;18216.726749099038;18446.35437759672;18832.058397079818;17795.19921603557;18108.547286317946;18127.731058825757;18216.180271887533;18323.54191145232;11730.061595072202;11990.940045299718;12045.578404755792;12053.63839084829;12061.396930153282;12528.123031387715;12766.61700148997;13671.111377760413;13813.309334533033;13818.309084545532;10853.40306283162;11010.669679909615;11060.668179954615;11200.663980080612;11353.886461135284;10398.22013349017;10585.735356616146;10638.680839574825;10675;10729.517171727135;9354.111359420844;9476.810463790547;9496.14534691881;9540.379875308083;9546.85679714791;7107.609081500567;7108.182559005573;7116.857662846817;7124.430045596444;7203.02759127532;7223.024891639771;7223.205447400676;7412.962935185506;7711.0746710395115;7728.806779830549 04:39:55 INFO - PID 19946 | |4;prototype.html;473.1719490890941;503.5166240409207;520.9114450543408;521.7469527279269;523.4765234765234;6134.294556126047;6161.383861613836;6284.685765711719;6366;6373.713182906768;6160.691965401735;6177.351378105302;6194.349593292707;6214.782482613109;6223.782167624134;5008.273800298964;5059.6458247922665;5060.54455099041;5074.492550744914;5075.086484432801;4699.953000470003;5005.849824505271;5044.167712327461;5044.621653375993;5085.525197692665;4786.1145688047645;4801;4841.709497430148;4901.8774530636665;4903.730294833783;4808.942032752806;4872.756362181876;4880.438749543795;4881.267809828554;4914.7788349524;4390.912181756379;4406.4357153210785;4421.955780442187;4433;4444.577765112342;5880.6765627890445;5997.7001149942325;6014.75940962361;6041.855141424819;6085.817425477243;5611.979687312564;5664.150377443375;5668.829935101912;5675.432456754333;5679.886402271973;6451.29035806061;6457.773977910798;6505.4795616350575;6510.414062734377;6622.668866556701;4773.1169733599045;4795.184818580849;4809.206480930642;4829.517048295177;4840.806367745286;6578.703958321887;6772;6861.313868613149;6874.828129296758;6913.585184888922;4936.7284799336385;4954.5788607968725;4957.677750946177;4964.801407943714;4990.426100998432;4960.255961605788;4973.577245934136;5008.449070602231;5015.548600625962;5054.545090941833;5574.804881829116;5659.179418984224;5674.744636491368;5674.773009079673;5679.148127780866;6664.733410663616;6683.598984060972;6686.866262674719;6688.29772873851;6695.129633147757;1722.2508208929155;1728.2482120277468;1730.6452177303622;1748.650269946016;1751.246963805576;1166.5651248963652;1212.9090318226167;1218.1168652726878;1223.877612238769;1234.9073819463576;5860.765569377178;5869.706514674249;5871.442212989759;5873.970630146822;5879.176915231871;6782.320983211604;6785.660716964132;6799.096253049154;6804.421624161902;6820.522563420513;5810.854728631733;5813.709314534256;5825.213596164548;5842.332937051138;5850.853728656818;3579.266250418658;3609.3864043112735;3626.4197728363333;3632.854685812538;3640.290143422007;6529.2817790043055;6543.770968016097;6552.115464412339;6566.67166641666;6571.112899758471;6975.581465112008;7011.684474198673;7027.227005029443;7044.330788575179;7058.11773528309;6458.451031662361;6465.510778350369;6506.544541882117;6530.1510803596175;6608.306127856602;6672.499562532732;6735.6968936397;6769.153855768029;6819.454443644596;6834.598907223908;6075.39002164418;6659.300773418915;6750.696218670073;6751.797446076625;6768.898466523105;6348.206474190726;6502.269047000176;6504.15445992018;6507.869842603027;6514.250861150838;4823.397075365579;4932.401724043309;4951.950480495149;4965.602751779921;4967.5971959208055;6499.740010399721;6598.769043083469;6657.900131497934;6715.7985260442265;6732.932670673426;6508.707108180049;6529.836754081186;6531.575447595891;6573.2580866071485;6626.403623673892;6532.680898281042;6550.868982620416;6565.343465653396;6570.8357291066195;6575.342465753386;6446.9355306448215;6504.15445992018;6504.707288171949;6556.770513032021;6558.934410656023;6628.204615446178;6653.600783952978;6678.263651450755;6680.565763225375;6717.395434410926;6437.549371544134;6444.613323200623;6454.741810327722;6460.483161346987;6502.4472919800455 04:39:55 INFO - PID 19946 | |5;yui.html;918.2057520244986;921.3550514639752;941.1529623338989;943.6178347769152;948.3598570964599;18874.245030198792;19458;19639;19736.210551577955;19808.30670926516;20113.59772804543;21642.35072947814;22001.889990550066;22106.668399974013;22118.33644990653;1052.626317657234;1074.2909679611457;1077.9892201078007;1078.919081068919;1078.9946050269737;1045.4511381524721;1048.8951104889488;1049.01916707878;1049.275999560301;1050.2228351020267;1107.6621630402724;1114.799336119498;1116.3469370418316;1116.9106471482262;1117.3407689463215;1113.9220254582142;1116.7264020314994;1116.8548088748412;1116.9329840209614;1117.8379135025416;811.7280710961835;812.6789917982424;813.4834380168577;814.3159745813485;817.5666896544782;16574;16668.58328541784;16734.49796506107;18912.621747564972;19102.80897191038;21615.459613509633;21847.563048739095;22444.43888902774;22454;22455;16721.916390418093;16940.23768930389;17214.7949643525;17229.88005779633;17372.652546949117;3711.2020915503294;3735.7571757835926;3739.869104581354;3744.363458212083;3749.6812770914503;117697;120574.39712801468;124564;125916;125969;17371.91314043447;17760.111994400228;18049.639007219783;18143.455696329132;18353;30524.389512210077;31369.686303137132;31936;32126;32147.67852321447;7959.482633628795;8312.04411492686;8929.464232146092;9006.964199117185;9093.04523025073;3969.960300396959;4107.835686572564;4126.236646220399;4128.298189307825;4133.400656904732;5344.4922732340365;5473.124300112043;5473.562115030868;5526.088195447786;5533.253010843565;1054.6730513540674;1079.6383211624036;1086.0605576176708;1092.5848177692585;1098.4782228441427;694.979150625482;713.0872483221485;714.9918614753173;723.706898706025;730.5069078372078;11555.306681599131;11977.281363118242;12056.51773929033;12413.255204687748;12946.705329466979;686.4028295382949;699.5383047188831;699.8740226759232;704.6864145455223;708.8546847896168;5598.543241288101;5665.745041473144;5673.801416950387;5679.801206957736;5752.13717942312;113216.43391782988;113764.43117784358;114121;116783.41608291904;119665.40167299283;93195;96199;98019;100609.49695251478;102007.4899625497;86778.13221867701;86853;89762.55118724365;90058.09941900628;90866;10490.265681402534;10568.788624227625;10583.31208471447;10607.363558186533;10851.782964340511;39050.41424378694;39330;40265.79867100646;40391.3941290875;40598.39102413407;51162.48837511726;51164.48835511597;51818.222726659886;51913.22130167975;52105;15071.773923391378;15565.533034008999;15593.610159746097;15735.055896646238;15816.209189540707;17850;17925.820741792417;18019.189136489153;18020.459386218434;18056.097195139926;15068.321925513159;15250;15425.768613471031;15428.151451670266;15451.84548154549;16397.59006024859;16521.421750238685;16529.173541322645;16651.50045498637;16659.91670041642;19017.33439329617;19206.231750729814;19622.80377196267;19665.21339146418;20070.49823754418;13769.586912392644;14668.413263469341;14672.633184170054;14679.48621798232;14702.63243418923;15059.247037647854;15107.848921510644;15116.622084447978;15183.089014659156;15461.458848940232 04:39:55 INFO - PID 19946 | __end_tp_report 04:39:55 INFO - PID 19946 | __start_cc_report 04:39:55 INFO - PID 19946 | _x_x_mozilla_cycle_collect,371 04:39:55 INFO - PID 19946 | __end_cc_report 04:39:55 INFO - PID 19946 | __startTimestamp1502451595387__endTimestamp 04:39:55 INFO - PID 19946 | 04:39:55 INFO - PID 19946 | ------- Summary: start ------- 04:39:55 INFO - PID 19946 | Number of tests: 6 04:39:55 INFO - PID 19946 | 04:39:55 INFO - PID 19946 | [#0] dojo.html Cycles:165 Average:6201.09 Median:6473.22 stddev:743.54 (11.5%) stddev-sans-first:705.97 04:39:55 INFO - PID 19946 | Values: 3139.5 3174.9 3194.2 3204.1 3204.2 6157.8 6309.3 6341.3 6369.8 6377.0 6127.8 6229.5 6241.2 6244.2 6266.7 5611.2 5675.8 5694.5 5697.9 5729.3 5710.2 5736.2 5738.8 5743.3 5797.3 5696.2 5709.4 5726.7 5793.3 5800.7 5170.1 5516.3 5649.9 5721.5 5734.3 5597.4 5636.3 5667.8 5687.5 5699.6 6136.2 6200.6 6218.0 6226.4 6239.5 6679.7 6704.1 6723.9 6747.5 6773.4 6788.0 6792.0 6859.0 6884.7 6888.7 6251.8 6260.9 6349.6 6353.8 6355.0 6840.7 6933.8 7008.2 7015.2 7022.0 6473.2 6556.4 6565.6 6655.9 6664.4 6691.1 6744.6 6751.9 6805.9 6812.0 6717.3 6724.0 6782.5 6815.0 6821.8 6582.6 6741.8 6759.2 6832.9 6839.0 5408.7 5455.3 5501.3 5512.8 5521.8 4691.4 4691.6 4710.6 4739.7 4775.4 5803.2 5805.0 5807.8 5873.7 5890.1 6681.7 6710.1 6733.7 6773.8 6857.4 5826.1 5826.1 5839.9 5842.2 5843.6 5858.4 5948.9 5957.4 5976.2 6010.6 6492.1 6503.3 6522.2 6597.9 6612.0 6678.2 6722.8 6797.6 6804.5 6815.7 6471.9 6476.5 6480.9 6490.8 6503.3 6568.4 6615.7 6638.0 6656.3 6661.3 6835.1 6879.9 6883.1 6885.3 6904.5 6557.1 6565.1 6664.3 6669.1 6669.3 6433.6 6528.0 6551.6 6552.3 6660.6 6506.1 6517.8 6520.5 6523.1 6526.3 6578.6 6591.3 6650.3 6686.2 6711.3 6331.3 6385.5 6435.6 6437.2 6441.8 04:39:55 INFO - PID 19946 | 04:39:55 INFO - PID 19946 | [#1] ext.html Cycles:180 Average:40155.26 Median:36055.05 stddev:56976.28 (158.0%) stddev-sans-first:57059.62 04:39:55 INFO - PID 19946 | Values: 837.5 873.9 901.4 905.5 1196.7 31664.2 32136.0 41908.8 42983.0 43153.0 44369.8 44663.6 44730.8 44903.0 44960.0 7411.5 7847.0 8043.7 8045.3 8056.8 7917.2 7960.2 7974.4 8005.3 8039.3 7915.6 7989.2 7992.9 7997.3 7998.0 7899.8 7967.4 7968.9 7971.2 7986.2 7885.6 7974.1 7977.4 7978.2 7980.2 39394.4 39587.4 39625.8 39648.2 39669.4 3318.9 3359.4 3360.6 3373.5 3375.5 32162.5 32309.8 32347.0 32366.0 32395.4 5067.7 5073.1 5081.8 5087.9 5096.5 199261.0 200789.0 201179.0 201276.0 201630.0 50902.4 50938.0 51054.5 51074.2 51229.7 53227.5 53296.5 53379.0 53387.2 53487.5 15198.0 17201.7 17478.0 17539.0 17542.7 12901.8 13987.7 14367.0 14375.5 14402.9 14396.5 14528.7 14584.8 14586.2 14683.3 5727.6 5770.8 5803.4 5833.2 5850.0 1018.5 1019.1 1039.0 1039.4 1043.9 23280.7 23368.3 23377.5 23464.0 23579.0 1136.2 1141.3 1144.0 1149.9 1154.3 12490.4 16129.8 16418.6 16465.5 16477.3 240193.0 242810.0 242823.0 243063.0 243170.0 60552.7 61149.4 61164.7 61222.0 61252.7 1490.4 1499.6 1528.0 1528.0 1532.5 27675.9 27722.9 28253.2 28717.1 28972.0 40182.4 40194.0 40354.4 40406.8 40412.4 211241.0 214463.0 215230.0 215241.0 215313.0 36214.5 36606.0 38684.4 38841.4 38883.4 47882.5 48411.0 48711.5 48802.5 48916.0 35489.3 35540.3 36009.6 36100.5 36657.3 34924.8 34979.8 35074.0 35361.6 35401.7 38581.0 38757.0 38801.8 38977.6 39043.6 34031.1 34071.1 34458.3 34699.7 34796.5 23910.9 25067.5 25290.2 25543.4 25589.9 04:39:55 INFO - PID 19946 | 04:39:55 INFO - PID 19946 | [#2] jquery.html Cycles:180 Average:35387.43 Median:23832.29 stddev:82236.98 (345.1%) stddev-sans-first:82426.11 04:39:55 INFO - PID 19946 | Values: 563.7 583.9 587.4 597.4 612.3 22656.9 23782.8 23881.8 23990.0 24512.9 25692.0 26416.5 26452.6 26464.3 26481.2 6533.9 6665.2 6688.3 6692.9 6709.8 5901.6 6173.2 6417.4 6439.2 6441.8 6392.6 6393.1 6400.8 6404.7 6405.0 6385.0 6399.6 6400.5 6401.5 6404.2 5974.6 5986.5 5988.7 5991.9 6000.8 30020.0 30559.5 30996.7 31449.8 31475.7 1929.6 1964.9 1966.8 1975.8 1976.5 19252.4 19490.9 19605.0 20500.9 20632.1 2426.3 2451.0 2480.6 2504.5 2509.0 140439.3 145191.0 147579.0 147705.0 147793.0 14226.9 14978.6 15021.3 15121.4 15430.4 23976.4 24071.6 24130.4 24157.0 24171.4 10376.1 10388.3 10466.9 10534.9 10544.8 8875.7 9729.0 9759.4 9913.1 10157.4 8322.2 9167.0 9465.5 9520.9 9542.5 2715.8 2755.3 2822.3 2869.5 2924.6 943.8 962.4 970.9 976.7 984.4 19117.3 19601.1 19881.9 19990.8 23292.4 1013.7 1042.1 1062.1 1066.4 1070.8 11012.8 11499.6 11607.2 11667.1 13324.7 475654.0 479032.0 489432.0 490580.0 492634.0 118076.0 118804.0 119592.0 119718.0 121381.4 1258.9 1274.4 1317.0 1318.8 1319.6 20359.0 20588.9 20815.1 21013.6 21052.9 55006.4 56178.4 56355.4 57795.0 58615.4 72262.6 72631.6 73905.0 74001.6 74400.0 12063.9 13925.0 14253.3 14662.9 14973.3 22558.9 23130.5 23256.8 24694.9 24800.3 18643.2 19012.7 19014.5 19021.2 19175.3 17264.2 17472.0 17558.0 17722.8 18064.2 19682.2 19814.0 19969.5 20154.1 20166.0 14665.3 14804.0 14809.4 14825.6 14897.9 18720.8 18768.1 18852.9 18953.4 19044.7 04:39:55 INFO - PID 19946 | 04:39:55 INFO - PID 19946 | [#3] mootools.html Cycles:180 Average:9062.05 Median:2622.51 stddev:6236.45 (237.8%) stddev-sans-first:6227.01 04:39:55 INFO - PID 19946 | Values: 1348.4 1382.1 1403.8 1412.5 1414.8 14333.1 14546.1 14850.3 14919.3 14923.4 15043.5 15273.7 15296.8 15312.5 15323.2 2575.6 2735.2 2797.2 2798.0 2798.9 2746.1 2769.3 2772.5 2773.5 2775.3 2744.7 2751.7 2759.8 2776.4 2780.1 2646.2 2653.8 2667.1 2668.0 2724.7 2612.9 2632.1 2646.9 2649.7 2652.6 14526.9 14541.2 14683.6 14743.0 14753.6 7725.1 7961.1 8010.0 8035.1 8040.0 5041.5 5145.3 5238.9 5282.7 5297.0 1867.0 1870.4 1872.8 1913.1 1916.0 22050.0 22219.2 22229.6 24478.4 25753.2 6683.9 6699.9 6779.8 7024.4 7042.4 16405.0 16422.1 16436.7 16475.3 16511.8 7777.7 7854.4 7875.7 7885.1 7894.1 7197.2 7293.6 7331.1 7334.4 7359.3 5013.6 5199.2 5199.6 5212.7 5238.3 1551.7 1554.1 1556.9 1560.0 1562.3 848.4 864.7 876.1 885.1 890.2 7016.1 7046.8 7951.3 8545.5 8564.8 985.0 1015.8 1018.6 1021.7 1024.3 5122.2 5153.8 5192.3 5299.8 5371.0 19351.6 19654.1 20102.4 20337.5 21106.1 20676.8 20738.8 20904.5 20915.0 21053.7 10492.6 11221.5 11226.3 11344.8 11464.1 8105.6 8414.5 8624.4 8837.0 8986.2 17729.7 17944.7 18216.7 18446.4 18832.1 17795.2 18108.5 18127.7 18216.2 18323.5 11730.1 11990.9 12045.6 12053.6 12061.4 12528.1 12766.6 13671.1 13813.3 13818.3 10853.4 11010.7 11060.7 11200.7 11353.9 10398.2 10585.7 10638.7 10675.0 10729.5 9354.1 9476.8 9496.1 9540.4 9546.9 7107.6 7108.2 7116.9 7124.4 7203.0 7223.0 7223.2 7413.0 7711.1 7728.8 04:39:55 INFO - PID 19946 | 04:39:55 INFO - PID 19946 | [#4] prototype.html Cycles:180 Average:5515.09 Median:6110.06 stddev:1563.06 (25.6%) stddev-sans-first:1520.94 04:39:55 INFO - PID 19946 | Values: 473.2 503.5 520.9 521.7 523.5 6134.3 6161.4 6284.7 6366.0 6373.7 6160.7 6177.4 6194.3 6214.8 6223.8 5008.3 5059.6 5060.5 5074.5 5075.1 4700.0 5005.8 5044.2 5044.6 5085.5 4786.1 4801.0 4841.7 4901.9 4903.7 4808.9 4872.8 4880.4 4881.3 4914.8 4390.9 4406.4 4422.0 4433.0 4444.6 5880.7 5997.7 6014.8 6041.9 6085.8 5612.0 5664.2 5668.8 5675.4 5679.9 6451.3 6457.8 6505.5 6510.4 6622.7 4773.1 4795.2 4809.2 4829.5 4840.8 6578.7 6772.0 6861.3 6874.8 6913.6 4936.7 4954.6 4957.7 4964.8 4990.4 4960.3 4973.6 5008.4 5015.5 5054.5 5574.8 5659.2 5674.7 5674.8 5679.1 6664.7 6683.6 6686.9 6688.3 6695.1 1722.3 1728.2 1730.6 1748.7 1751.2 1166.6 1212.9 1218.1 1223.9 1234.9 5860.8 5869.7 5871.4 5874.0 5879.2 6782.3 6785.7 6799.1 6804.4 6820.5 5810.9 5813.7 5825.2 5842.3 5850.9 3579.3 3609.4 3626.4 3632.9 3640.3 6529.3 6543.8 6552.1 6566.7 6571.1 6975.6 7011.7 7027.2 7044.3 7058.1 6458.5 6465.5 6506.5 6530.2 6608.3 6672.5 6735.7 6769.2 6819.5 6834.6 6075.4 6659.3 6750.7 6751.8 6768.9 6348.2 6502.3 6504.2 6507.9 6514.3 4823.4 4932.4 4952.0 4965.6 4967.6 6499.7 6598.8 6657.9 6715.8 6732.9 6508.7 6529.8 6531.6 6573.3 6626.4 6532.7 6550.9 6565.3 6570.8 6575.3 6446.9 6504.2 6504.7 6556.8 6558.9 6628.2 6653.6 6678.3 6680.6 6717.4 6437.5 6444.6 6454.7 6460.5 6502.4 04:39:55 INFO - PID 19946 | 04:39:55 INFO - PID 19946 | [#5] yui.html Cycles:180 Average:23279.10 Median:18893.43 stddev:31859.97 (168.6%) stddev-sans-first:31905.11 04:39:55 INFO - PID 19946 | Values: 918.2 921.4 941.2 943.6 948.4 18874.2 19458.0 19639.0 19736.2 19808.3 20113.6 21642.4 22001.9 22106.7 22118.3 1052.6 1074.3 1078.0 1078.9 1079.0 1045.5 1048.9 1049.0 1049.3 1050.2 1107.7 1114.8 1116.3 1116.9 1117.3 1113.9 1116.7 1116.9 1116.9 1117.8 811.7 812.7 813.5 814.3 817.6 16574.0 16668.6 16734.5 18912.6 19102.8 21615.5 21847.6 22444.4 22454.0 22455.0 16721.9 16940.2 17214.8 17229.9 17372.7 3711.2 3735.8 3739.9 3744.4 3749.7 117697.0 120574.4 124564.0 125916.0 125969.0 17371.9 17760.1 18049.6 18143.5 18353.0 30524.4 31369.7 31936.0 32126.0 32147.7 7959.5 8312.0 8929.5 9007.0 9093.0 3970.0 4107.8 4126.2 4128.3 4133.4 5344.5 5473.1 5473.6 5526.1 5533.3 1054.7 1079.6 1086.1 1092.6 1098.5 695.0 713.1 715.0 723.7 730.5 11555.3 11977.3 12056.5 12413.3 12946.7 686.4 699.5 699.9 704.7 708.9 5598.5 5665.7 5673.8 5679.8 5752.1 113216.4 113764.4 114121.0 116783.4 119665.4 93195.0 96199.0 98019.0 100609.5 102007.5 86778.1 86853.0 89762.6 90058.1 90866.0 10490.3 10568.8 10583.3 10607.4 10851.8 39050.4 39330.0 40265.8 40391.4 40598.4 51162.5 51164.5 51818.2 51913.2 52105.0 15071.8 15565.5 15593.6 15735.1 15816.2 17850.0 17925.8 18019.2 18020.5 18056.1 15068.3 15250.0 15425.8 15428.2 15451.8 16397.6 16521.4 16529.2 16651.5 16659.9 19017.3 19206.2 19622.8 19665.2 20070.5 13769.6 14668.4 14672.6 14679.5 14702.6 15059.2 15107.8 15116.6 15183.1 15461.5 04:39:55 INFO - PID 19946 | -------- Summary: end -------- 04:39:55 INFO - PID 19946 | 04:39:56 INFO - TEST-INFO | 19946: exit 0 04:39:56 INFO - TEST-OK | dromaeo_css | took 1112208ms 04:39:56 INFO - TEST-START | kraken 04:39:56 INFO - Initialising browser for kraken test... 04:39:56 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33489/getInfo.html -profile /tmp/tmpZNQfei/profile 04:39:56 INFO - TEST-INFO | started process 20327 (/builds/slave/test/build/application/firefox/firefox http://localhost:33489/getInfo.html) 04:40:01 INFO - TEST-INFO | 20327: exit 0 04:40:02 INFO - Browser initialized. 04:40:02 INFO - Running cycle 1/1 for kraken test... 04:40:02 INFO - TEST-INFO | started process 20558 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZNQfei/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:40:02 INFO - PID 20558 | 04:40:02 INFO - PID 20558 | (/builds/slave/test/build/application/firefox/firefox:20612): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:40:02 INFO - PID 20558 | 04:40:13 INFO - PID 20558 | RSS: Main: 167677952 04:40:13 INFO - PID 20558 | 04:40:16 INFO - PID 20558 | Cycle 1(1): loaded http://localhost:33489/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33489/tests/kraken/driver.html?testName=audio-beat-detection) 04:40:16 INFO - PID 20558 | RSS: Main: 173785088 04:40:16 INFO - PID 20558 | 04:40:19 INFO - PID 20558 | Cycle 1(1): loaded http://localhost:33489/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33489/tests/kraken/driver.html?testName=audio-dft) 04:40:20 INFO - PID 20558 | RSS: Main: 177483776 04:40:20 INFO - PID 20558 | 04:40:23 INFO - PID 20558 | Cycle 1(1): loaded http://localhost:33489/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33489/tests/kraken/driver.html?testName=audio-fft) 04:40:24 INFO - PID 20558 | RSS: Main: 172015616 04:40:24 INFO - PID 20558 | 04:40:26 INFO - PID 20558 | Cycle 1(1): loaded http://localhost:33489/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33489/tests/kraken/driver.html?testName=audio-oscillator) 04:40:26 INFO - PID 20558 | RSS: Main: 172167168 04:40:26 INFO - PID 20558 | 04:40:29 INFO - PID 20558 | Cycle 1(1): loaded http://localhost:33489/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33489/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:40:29 INFO - PID 20558 | RSS: Main: 172556288 04:40:29 INFO - PID 20558 | 04:40:32 INFO - PID 20558 | Cycle 1(1): loaded http://localhost:33489/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33489/tests/kraken/driver.html?testName=imaging-darkroom) 04:40:33 INFO - PID 20558 | RSS: Main: 174149632 04:40:33 INFO - PID 20558 | 04:40:37 INFO - PID 20558 | Cycle 1(1): loaded http://localhost:33489/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33489/tests/kraken/driver.html?testName=imaging-desaturate) 04:40:37 INFO - PID 20558 | RSS: Main: 173322240 04:40:37 INFO - PID 20558 | 04:40:40 INFO - PID 20558 | Cycle 1(1): loaded http://localhost:33489/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33489/tests/kraken/driver.html?testName=json-parse-financial) 04:40:41 INFO - PID 20558 | RSS: Main: 174546944 04:40:41 INFO - PID 20558 | 04:40:42 INFO - PID 20558 | Cycle 1(1): loaded http://localhost:33489/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33489/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:40:43 INFO - PID 20558 | RSS: Main: 174440448 04:40:43 INFO - PID 20558 | 04:40:45 INFO - PID 20558 | Cycle 1(1): loaded http://localhost:33489/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33489/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:40:46 INFO - PID 20558 | RSS: Main: 174313472 04:40:46 INFO - PID 20558 | 04:40:48 INFO - PID 20558 | Cycle 1(1): loaded http://localhost:33489/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33489/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:40:48 INFO - PID 20558 | RSS: Main: 174157824 04:40:48 INFO - PID 20558 | 04:40:51 INFO - PID 20558 | Cycle 1(1): loaded http://localhost:33489/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33489/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:40:52 INFO - PID 20558 | RSS: Main: 174137344 04:40:52 INFO - PID 20558 | 04:40:55 INFO - PID 20558 | Cycle 1(1): loaded http://localhost:33489/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33489/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:40:56 INFO - PID 20558 | RSS: Main: 174718976 04:40:56 INFO - PID 20558 | 04:40:57 INFO - PID 20558 | Cycle 1(1): loaded http://localhost:33489/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33489/tests/kraken/driver.html?testName=ai-astar) 04:40:58 INFO - PID 20558 | RSS: Main: 175796224 04:40:58 INFO - PID 20558 | 04:40:58 INFO - PID 20558 | __start_tp_report 04:40:58 INFO - PID 20558 | _x_x_mozilla_page_load 04:40:58 INFO - PID 20558 | _x_x_mozilla_page_load_details 04:40:58 INFO - PID 20558 | |i|pagename|runs| 04:40:58 INFO - PID 20558 | |0;ai-astar;101;107;101;107;109;110;115;104;114;110 04:40:58 INFO - PID 20558 | |1;audio-beat-detection;157;124;146;184;137;148;141;121;185;127 04:40:58 INFO - PID 20558 | |2;audio-dft;163;165;164;171;164;169;172;165;166;165 04:40:58 INFO - PID 20558 | |3;audio-fft;75;80;76;175;75;75;80;84;168;75 04:40:58 INFO - PID 20558 | |4;audio-oscillator;79;79;137;80;79;79;125;79;80;129 04:40:58 INFO - PID 20558 | |5;imaging-gaussian-blur;114;114;114;114;114;114;119;119;115;115 04:40:58 INFO - PID 20558 | |6;imaging-darkroom;152;155;151;151;154;150;150;151;151;152 04:40:58 INFO - PID 20558 | |7;imaging-desaturate;97;93;94;94;94;94;94;94;99;94 04:40:58 INFO - PID 20558 | |8;json-parse-financial;55;55;53;54;53;53;53;53;58;57 04:40:58 INFO - PID 20558 | |9;json-stringify-tinderbox;55;56;55;63;55;54;61;56;56;55 04:40:58 INFO - PID 20558 | |10;stanford-crypto-aes;78;77;78;77;76;102;80;81;77;85 04:40:58 INFO - PID 20558 | |11;stanford-crypto-ccm;124;122;122;135;126;123;124;123;139;169 04:40:58 INFO - PID 20558 | |12;stanford-crypto-pbkdf2;182;175;173;177;180;171;170;179;175;170 04:40:58 INFO - PID 20558 | |13;stanford-crypto-sha256-iterative;70;68;71;69;68;69;67;71;67;66 04:40:58 INFO - PID 20558 | __end_tp_report 04:40:58 INFO - PID 20558 | __start_cc_report 04:40:58 INFO - PID 20558 | _x_x_mozilla_cycle_collect,980 04:40:58 INFO - PID 20558 | __end_cc_report 04:40:58 INFO - PID 20558 | __startTimestamp1502451658175__endTimestamp 04:40:58 INFO - PID 20558 | 04:40:58 INFO - PID 20558 | ------- Summary: start ------- 04:40:58 INFO - PID 20558 | Number of tests: 14 04:40:58 INFO - PID 20558 | 04:40:58 INFO - PID 20558 | [#0] ai-astar Cycles:10 Average:107.80 Median:109.50 stddev:4.83 (4.4%) stddev-sans-first:4.45 04:40:58 INFO - PID 20558 | Values: 101.0 107.0 101.0 107.0 109.0 110.0 115.0 104.0 114.0 110.0 04:40:58 INFO - PID 20558 | 04:40:58 INFO - PID 20558 | [#1] audio-beat-detection Cycles:10 Average:147.00 Median:147.00 stddev:22.74 (15.5%) stddev-sans-first:23.84 04:40:58 INFO - PID 20558 | Values: 157.0 124.0 146.0 184.0 137.0 148.0 141.0 121.0 185.0 127.0 04:40:58 INFO - PID 20558 | 04:40:58 INFO - PID 20558 | [#2] audio-dft Cycles:10 Average:166.40 Median:165.50 stddev:3.13 (1.9%) stddev-sans-first:3.07 04:40:58 INFO - PID 20558 | Values: 163.0 165.0 164.0 171.0 164.0 169.0 172.0 165.0 166.0 165.0 04:40:58 INFO - PID 20558 | 04:40:58 INFO - PID 20558 | [#3] audio-fft Cycles:10 Average:96.30 Median:75.50 stddev:39.78 (52.7%) stddev-sans-first:41.44 04:40:58 INFO - PID 20558 | Values: 75.0 80.0 76.0 175.0 75.0 75.0 80.0 84.0 168.0 75.0 04:40:58 INFO - PID 20558 | 04:40:58 INFO - PID 20558 | [#4] audio-oscillator Cycles:10 Average:94.60 Median:79.00 stddev:24.83 (31.4%) stddev-sans-first:25.69 04:40:58 INFO - PID 20558 | Values: 79.0 79.0 137.0 80.0 79.0 79.0 125.0 79.0 80.0 129.0 04:40:58 INFO - PID 20558 | 04:40:58 INFO - PID 20558 | [#5] imaging-gaussian-blur Cycles:10 Average:115.20 Median:114.50 stddev:2.04 (1.8%) stddev-sans-first:2.12 04:40:58 INFO - PID 20558 | Values: 114.0 114.0 114.0 114.0 114.0 114.0 119.0 119.0 115.0 115.0 04:40:58 INFO - PID 20558 | 04:40:58 INFO - PID 20558 | [#6] imaging-darkroom Cycles:10 Average:151.70 Median:151.50 stddev:1.64 (1.1%) stddev-sans-first:1.73 04:40:58 INFO - PID 20558 | Values: 152.0 155.0 151.0 151.0 154.0 150.0 150.0 151.0 151.0 152.0 04:40:58 INFO - PID 20558 | 04:40:58 INFO - PID 20558 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:94.00 stddev:1.83 (1.9%) stddev-sans-first:1.74 04:40:58 INFO - PID 20558 | Values: 97.0 93.0 94.0 94.0 94.0 94.0 94.0 94.0 99.0 94.0 04:40:58 INFO - PID 20558 | 04:40:58 INFO - PID 20558 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.50 stddev:1.84 (3.4%) stddev-sans-first:1.94 04:40:58 INFO - PID 20558 | Values: 55.0 55.0 53.0 54.0 53.0 53.0 53.0 53.0 58.0 57.0 04:40:58 INFO - PID 20558 | 04:40:58 INFO - PID 20558 | [#9] json-stringify-tinderbox Cycles:10 Average:56.60 Median:56.00 stddev:2.95 (5.3%) stddev-sans-first:3.07 04:40:58 INFO - PID 20558 | Values: 55.0 56.0 55.0 63.0 55.0 54.0 61.0 56.0 56.0 55.0 04:40:58 INFO - PID 20558 | 04:40:58 INFO - PID 20558 | [#10] stanford-crypto-aes Cycles:10 Average:81.10 Median:78.00 stddev:7.81 (10.0%) stddev-sans-first:8.20 04:40:58 INFO - PID 20558 | Values: 78.0 77.0 78.0 77.0 76.0 102.0 80.0 81.0 77.0 85.0 04:40:58 INFO - PID 20558 | 04:40:58 INFO - PID 20558 | [#11] stanford-crypto-ccm Cycles:10 Average:130.70 Median:125.00 stddev:14.67 (11.7%) stddev-sans-first:15.36 04:40:58 INFO - PID 20558 | Values: 124.0 122.0 122.0 135.0 126.0 123.0 124.0 123.0 139.0 169.0 04:40:58 INFO - PID 20558 | 04:40:58 INFO - PID 20558 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.20 Median:176.00 stddev:4.26 (2.4%) stddev-sans-first:3.75 04:40:58 INFO - PID 20558 | Values: 182.0 175.0 173.0 177.0 180.0 171.0 170.0 179.0 175.0 170.0 04:40:58 INFO - PID 20558 | 04:40:58 INFO - PID 20558 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:69.00 stddev:1.71 (2.5%) stddev-sans-first:1.74 04:40:58 INFO - PID 20558 | Values: 70.0 68.0 71.0 69.0 68.0 69.0 67.0 71.0 67.0 66.0 04:40:58 INFO - PID 20558 | -------- Summary: end -------- 04:40:58 INFO - PID 20558 | 04:40:58 INFO - PID 20558 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 04:40:58 INFO - TEST-INFO | 20558: exit 0 04:40:58 INFO - TEST-OK | kraken | took 62785ms 04:40:58 INFO - SUITE-END | took 1174s 04:40:59 INFO - Completed test suite (00:19:35) 04:40:59 INFO - javascript benchmark 04:40:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8642.223142831926, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3139.544766008929, 3174.936501269976, 3194.217416732901, 3204.1028512016637, 3204.182933351995, 6157.815265542035, 6309.305976342599, 6341.334159913212, 6369.840753981147, 6377.04344348348, 6127.7855275065385, 6229.532785041118, 6241.21984751906, 6244.219472565929, 6266.749330026793, 5611.158326251075, 5675.829725108243, 5694.4590263924865, 5697.857553561153, 5729.312482502106, 5710.200571919934, 5736.225609542721, 5738.770449182049, 5743.253377060976, 5797.333306669724, 5696.231008813819, 5709.400512946164, 5726.656400615955, 5793.33376661683, 5800.680962547058, 5170.06122703986, 5516.282883225195, 5649.887002259932, 5721.4850663440075, 5734.311882574076, 5597.412271711452, 5636.323641163087, 5667.829965101054, 5687.488126068673, 5699.629524080921, 6136.202293701835, 6200.62796232223, 6218.00511918091, 6226.40849119333, 6239.500839932794, 6679.666016699194, 6704.094947182117, 6723.899141512881, 6747.493937954673, 6773.356531129535, 6788.049673045777, 6792.0151578021405, 6858.965705171492, 6884.724611015554, 6888.724451022003, 6251.78118765841, 6260.937390626126, 6349.555531112824, 6353.777617783356, 6355.046742988588, 6840.657967101625, 6933.826654333581, 7008.229094799568, 7015.228324884259, 7022.0, 6473.2232132144445, 6556.377144171296, 6565.606063636197, 6655.900161497581, 6664.400203981665, 6691.063251144844, 6744.62904540255, 6751.932480675228, 6805.863882722417, 6812.046313516112, 6717.261101278856, 6723.966380168068, 6782.457403407716, 6815.045893574904, 6821.829454263584, 6582.605043697394, 6741.831454213684, 6759.155105611799, 6832.863342733118, 6839.0, 5408.729563521808, 5455.290812194389, 5501.284832971688, 5512.834614961557, 5521.834344969577, 4691.366665500113, 4691.55430234134, 4710.62315014798, 4739.715617062919, 4775.355327030876, 5803.216565763652, 5804.970975145097, 5807.796727114531, 5873.735681894325, 5890.087036509348, 6681.699323530453, 6710.127683401127, 6733.730650774012, 6773.796786096425, 6857.417119544894, 5826.126081087786, 5826.126081087786, 5839.948809214297, 5842.182094506772, 5843.620164689281, 5858.384869588717, 5948.881022379528, 5957.434043765836, 5976.193213916153, 6010.579259451883, 6492.09110724499, 6503.317151699003, 6522.249941256721, 6597.901031484627, 6612.0, 6678.2320033194865, 6722.831929201809, 6797.626130562867, 6804.523683342119, 6815.727370905307, 6471.902921456276, 6476.481881449567, 6480.935190648033, 6490.8052758417325, 6503.25212600557, 6568.441682457044, 6615.735370585123, 6638.0, 6656.301088385843, 6661.333866613494, 6835.14560679915, 6879.931200687929, 6883.070785444102, 6885.311468853075, 6904.516683832084, 6557.147570815763, 6565.113709649328, 6664.266930637723, 6669.1330127083165, 6669.299723529154, 6433.613983161025, 6528.0, 6551.6069035858, 6552.312007239362, 6660.600363977983, 6506.121673574196, 6517.771877984248, 6520.478361731145, 6523.11937888379, 6526.314736952741, 6578.572392794453, 6591.34086591337, 6650.334966503505, 6686.231083425274, 6711.261761206166, 6331.335209803088, 6385.489160867212, 6435.646039467687, 6437.227532696106, 6441.806745797634], "value": 6144.955050997169, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [837.5309826497162, 873.8907636545433, 901.3690416708304, 905.5381755304793, 1196.6709154982384, 31664.208394790112, 32136.0, 41908.79045604772, 42983.0, 43153.0, 44369.778151109196, 44663.553364466425, 44730.776346118306, 44903.0, 44960.0, 7411.48119631626, 7846.979892613981, 8043.678252869879, 8045.275925166734, 8056.8388632227325, 7917.168697286788, 7960.203979602023, 7974.401919856005, 8005.279524842763, 8039.276465118138, 7915.643796029192, 7989.161138080505, 7992.920070799305, 7997.320227780646, 7998.0, 7899.763007109796, 7967.44227904047, 7968.8804667929735, 7971.163027882076, 7986.2013798620255, 7885.566293853835, 7974.122846486882, 7977.401694872908, 7978.162292959214, 7980.162082981262, 39394.409083863764, 39587.40618890719, 39625.80187099075, 39648.20703585912, 39669.40495892564, 3318.900432987014, 3359.395308844407, 3360.647132051148, 3373.544571482843, 3375.476801095834, 32162.517562236586, 32309.83845080783, 32347.0, 32366.0, 32395.352092958245, 5067.670601410897, 5073.1122053640465, 5081.822136225252, 5087.872803179913, 5096.490350964911, 199261.0, 200789.0, 201179.0, 201276.0, 201630.0, 50902.41878230977, 50938.0, 51054.489455105715, 51074.23388649173, 51229.7438512805, 53227.467725323026, 53296.46703532915, 53379.0, 53387.19919201215, 53487.46512534825, 15198.0, 17201.7419738704, 17478.03870787119, 17539.0, 17542.736858947126, 12901.806472902914, 13987.650308742363, 14367.0, 14375.496857609933, 14402.927985360217, 14396.496122635866, 14528.709425811636, 14584.781228281585, 14586.197759123137, 14683.339249733788, 5727.570432217601, 5770.798022069207, 5803.361630220672, 5833.241678581842, 5849.970750146222, 1018.5467466977273, 1019.1388276906002, 1039.0388890276463, 1039.4231201682987, 1043.9321444106108, 23280.65079023816, 23368.298951031495, 23377.532449350918, 23464.0, 23579.0, 1136.176272202663, 1141.3494308244387, 1143.988560114405, 1149.942053310956, 1154.342025045716, 12490.375481225903, 16129.838701612834, 16418.589535261475, 16465.506034818976, 16477.34090636361, 240193.0, 242810.0, 242823.0, 243063.0, 243170.0, 60552.69723651354, 61149.38850611437, 61164.69417652972, 61222.0, 61252.69373653103, 1490.4410845932775, 1499.580117567061, 1527.9694406111817, 1527.9847201527843, 1532.4712974023944, 27675.86162069177, 27722.861385692944, 28253.15240542787, 28717.13848584546, 28972.0, 40182.397264040475, 40194.0, 40354.39468408035, 40406.79796601116, 40412.3938140934, 211241.0, 214463.0, 215230.0, 215241.0, 215313.0, 36214.4567831488, 36606.0, 38684.41973370346, 38841.417378739905, 38883.416748749354, 47882.5211747892, 48411.0, 48711.512884870695, 48802.511974879795, 48916.0, 35489.290214195054, 35540.289194216486, 36009.639903600626, 36100.458493122096, 36657.26685466329, 34924.825375872955, 34979.82510087535, 35074.0, 35361.64638353584, 35401.74187019204, 38581.0, 38757.0, 38801.805990969864, 38977.61022389853, 39043.609563904, 34031.14922126967, 34071.148221294665, 34458.310833782685, 34699.65300346964, 34796.47805282872, 23910.880445598355, 25067.498650026533, 25290.241292761246, 25543.361415964006, 25589.872050639628], "value": 18193.921893622457, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [563.6589863132805, 583.8569550460138, 587.3979171349368, 597.3697748874937, 612.3111499562991, 22656.88671556642, 23782.810859457044, 23881.76118238817, 23990.040398384044, 24512.877435612798, 25692.0, 26416.471670566625, 26452.603210951853, 26464.338391540223, 26481.205563833068, 6533.934660653392, 6665.233498147702, 6688.331166883321, 6692.9330706693045, 6709.798706038802, 5901.645901245917, 6173.228346456693, 6417.39034791694, 6439.22729272488, 6441.806745797634, 6392.584482008655, 6393.136926514931, 6400.807975760711, 6404.743810247608, 6405.007223880283, 6385.042243663464, 6399.584027038274, 6400.487960963112, 6401.519886008545, 6404.167458230447, 5974.551908606828, 5986.491148252404, 5988.730507127189, 5991.91012134814, 6000.789972350991, 30020.0, 30559.541606875915, 30996.690033099607, 31449.842750786098, 31475.685243147505, 1929.604431091623, 1964.8919309437974, 1966.803319668036, 1975.7629084509952, 1976.456474469518, 19252.4224273272, 19490.90254548718, 19605.0, 20500.897495512578, 20632.071556779832, 2426.344886880532, 2450.9509809803703, 2480.6403071554705, 2504.4615407687434, 2509.04656230633, 140439.29780351135, 145191.0, 147579.0, 147705.0, 147793.0, 14226.85773142276, 14978.625534361729, 15021.324040418207, 15121.39514419411, 15430.38278468871, 23976.40058998504, 24071.555706657655, 24130.396740081287, 24157.0, 24171.395715107265, 10376.118029967538, 10388.272820902464, 10466.895331046744, 10534.947325263478, 10544.841827372595, 8875.73372798817, 9728.95135524332, 9759.365641233297, 9913.107820296063, 10157.441340726331, 8322.20939010793, 9167.0, 9465.52672366379, 9520.905400811811, 9542.52287385628, 2715.827338129528, 2755.3249453883927, 2822.252103192656, 2869.483492971285, 2924.6344206974127, 943.834828904939, 962.3744566032135, 970.8592254123113, 976.7460460280379, 984.4388698441819, 19117.330893418664, 19601.1179496923, 19881.900590496956, 19990.800091998895, 23292.41768955756, 1013.6806905824642, 1042.0985847242082, 1062.0600768319937, 1066.4294602387686, 1070.7751372211767, 11012.834807477895, 11499.570352311192, 11607.245529040585, 11667.11640132326, 13324.666883327995, 475654.0, 479032.0, 489432.0, 490580.0, 492634.0, 118076.0, 118804.0, 119592.0, 119718.0, 121381.39309303397, 1258.930758808256, 1274.3819247664876, 1316.993415032938, 1318.7626227279181, 1319.6107148391247, 20359.0, 20588.86761228147, 20815.063322150236, 21013.579728405653, 21052.894735526224, 55006.44993550013, 56178.43821561732, 56355.436445635016, 57795.0, 58615.4138458627, 72262.63868680834, 72631.63684181757, 73905.0, 74001.6299918497, 74400.0, 12063.939680301542, 13924.955628327712, 14253.28733563297, 14662.926685366505, 14973.326200320795, 22558.887205563868, 23130.537389251785, 23256.76743232546, 24694.876525617256, 24800.25599232026, 18643.161057752164, 19012.714809277597, 19014.524636884187, 19021.23915043438, 19175.32886348971, 17264.223109960334, 17472.0, 17558.0, 17722.822771772633, 18064.187111579748, 19682.21271149138, 19814.0, 19969.500762481053, 20154.093065812365, 20166.0, 14665.340059697553, 14803.96372253975, 14809.407623694931, 14825.629359266104, 14897.925510372379, 18720.812791872453, 18768.061596919826, 18852.905735471235, 18953.43139705811, 19044.666873318736], "value": 12261.157943517832, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1348.4134401535332, 1382.1223523062858, 1403.8104855844465, 1412.4773833681536, 1414.8231471066117, 14333.140011599311, 14546.127232366065, 14850.25748712564, 14919.32863021164, 14923.403063877446, 15043.473478428257, 15273.694526109472, 15296.847031529682, 15312.46406375777, 15323.157226352547, 2575.5621544337414, 2735.1521028481134, 2797.1748334241347, 2797.9860100699466, 2798.9020384286555, 2746.148693904896, 2769.2661444717064, 2772.487089888377, 2773.514634938888, 2775.278427608826, 2744.656917885264, 2751.683556391021, 2759.84820834855, 2776.3891943772337, 2780.06867699321, 2646.2193652872447, 2653.7744291735225, 2667.119850449357, 2667.9599806002925, 2724.6730392352847, 2612.8954841806303, 2632.1313966391044, 2646.8808903599192, 2649.748273913975, 2652.562327216007, 14526.854731452657, 14541.200233987127, 14683.632909177357, 14743.041702289316, 14753.631159220999, 7725.072991241031, 7961.1242763295995, 8009.95995020027, 8035.116137224901, 8039.995000624922, 5041.470645582198, 5145.279660847485, 5238.947610523884, 5282.735863206863, 5297.020051290485, 1866.953326166843, 1870.4014715290975, 1872.7659042619673, 1913.1104036622899, 1915.9616807663908, 22050.0, 22219.22232721863, 22229.555408891894, 24478.388040299134, 25753.227403177934, 6683.866322673519, 6699.899501507482, 6779.830504237335, 7024.367806897404, 7042.366187043191, 16405.0, 16422.09678467672, 16436.6712665746, 16475.258613362428, 16511.752323715154, 7777.72777952769, 7854.410919181084, 7875.684972601146, 7885.093214280431, 7894.131645518988, 7197.208307086216, 7293.598852063187, 7331.083614548182, 7334.376577990931, 7359.264073592598, 5013.623978201649, 5199.220116982483, 5199.636025478256, 5212.661177023557, 5238.319018527567, 1551.7129331073788, 1554.0830909763295, 1556.9299381527858, 1559.9766003509956, 1562.2735428025794, 848.440029580474, 864.7405778266495, 876.1019954546514, 885.106042896679, 890.2299510923134, 7016.122984626922, 7046.753363632249, 7951.284384405431, 8545.529995850164, 8564.785880352916, 985.0002247718571, 1015.8222311095528, 1018.6282007067367, 1021.6985989133224, 1024.3393011507685, 5122.231665250242, 5153.793848246103, 5192.273081768555, 5299.761510732026, 5370.9731451342495, 19351.612967740566, 19654.115564799617, 20102.39692809218, 20337.49156271105, 21106.05022773979, 20676.793232067786, 20738.792612073987, 20904.47738806542, 20915.0, 21053.684194737092, 10492.580296788043, 11221.495032723547, 11226.270292430965, 11344.773104537862, 11464.140189485823, 8105.594720264081, 8414.495130292202, 8624.396292259482, 8837.0, 8986.236169925629, 17729.734053989458, 17944.730829037835, 18216.726749099038, 18446.35437759672, 18832.058397079818, 17795.19921603557, 18108.547286317946, 18127.731058825757, 18216.180271887533, 18323.54191145232, 11730.061595072202, 11990.940045299718, 12045.578404755792, 12053.63839084829, 12061.396930153282, 12528.123031387715, 12766.61700148997, 13671.111377760413, 13813.309334533033, 13818.309084545532, 10853.40306283162, 11010.669679909615, 11060.668179954615, 11200.663980080612, 11353.886461135284, 10398.22013349017, 10585.735356616146, 10638.680839574825, 10675.0, 10729.517171727135, 9354.111359420844, 9476.810463790547, 9496.14534691881, 9540.379875308083, 9546.85679714791, 7107.609081500567, 7108.182559005573, 7116.857662846817, 7124.430045596444, 7203.02759127532, 7223.024891639771, 7223.205447400676, 7412.962935185506, 7711.0746710395115, 7728.806779830549], "value": 6584.381953676063, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [473.1719490890941, 503.5166240409207, 520.9114450543408, 521.7469527279269, 523.4765234765234, 6134.294556126047, 6161.383861613836, 6284.685765711719, 6366.0, 6373.713182906768, 6160.691965401735, 6177.351378105302, 6194.349593292707, 6214.782482613109, 6223.782167624134, 5008.273800298964, 5059.6458247922665, 5060.54455099041, 5074.492550744914, 5075.086484432801, 4699.953000470003, 5005.849824505271, 5044.167712327461, 5044.621653375993, 5085.525197692665, 4786.1145688047645, 4801.0, 4841.709497430148, 4901.8774530636665, 4903.730294833783, 4808.942032752806, 4872.756362181876, 4880.438749543795, 4881.267809828554, 4914.7788349524, 4390.912181756379, 4406.4357153210785, 4421.955780442187, 4433.0, 4444.577765112342, 5880.6765627890445, 5997.7001149942325, 6014.75940962361, 6041.855141424819, 6085.817425477243, 5611.979687312564, 5664.150377443375, 5668.829935101912, 5675.432456754333, 5679.886402271973, 6451.29035806061, 6457.773977910798, 6505.4795616350575, 6510.414062734377, 6622.668866556701, 4773.1169733599045, 4795.184818580849, 4809.206480930642, 4829.517048295177, 4840.806367745286, 6578.703958321887, 6772.0, 6861.313868613149, 6874.828129296758, 6913.585184888922, 4936.7284799336385, 4954.5788607968725, 4957.677750946177, 4964.801407943714, 4990.426100998432, 4960.255961605788, 4973.577245934136, 5008.449070602231, 5015.548600625962, 5054.545090941833, 5574.804881829116, 5659.179418984224, 5674.744636491368, 5674.773009079673, 5679.148127780866, 6664.733410663616, 6683.598984060972, 6686.866262674719, 6688.29772873851, 6695.129633147757, 1722.2508208929155, 1728.2482120277468, 1730.6452177303622, 1748.650269946016, 1751.246963805576, 1166.5651248963652, 1212.9090318226167, 1218.1168652726878, 1223.877612238769, 1234.9073819463576, 5860.765569377178, 5869.706514674249, 5871.442212989759, 5873.970630146822, 5879.176915231871, 6782.320983211604, 6785.660716964132, 6799.096253049154, 6804.421624161902, 6820.522563420513, 5810.854728631733, 5813.709314534256, 5825.213596164548, 5842.332937051138, 5850.853728656818, 3579.266250418658, 3609.3864043112735, 3626.4197728363333, 3632.854685812538, 3640.290143422007, 6529.2817790043055, 6543.770968016097, 6552.115464412339, 6566.67166641666, 6571.112899758471, 6975.581465112008, 7011.684474198673, 7027.227005029443, 7044.330788575179, 7058.11773528309, 6458.451031662361, 6465.510778350369, 6506.544541882117, 6530.1510803596175, 6608.306127856602, 6672.499562532732, 6735.6968936397, 6769.153855768029, 6819.454443644596, 6834.598907223908, 6075.39002164418, 6659.300773418915, 6750.696218670073, 6751.797446076625, 6768.898466523105, 6348.206474190726, 6502.269047000176, 6504.15445992018, 6507.869842603027, 6514.250861150838, 4823.397075365579, 4932.401724043309, 4951.950480495149, 4965.602751779921, 4967.5971959208055, 6499.740010399721, 6598.769043083469, 6657.900131497934, 6715.7985260442265, 6732.932670673426, 6508.707108180049, 6529.836754081186, 6531.575447595891, 6573.2580866071485, 6626.403623673892, 6532.680898281042, 6550.868982620416, 6565.343465653396, 6570.8357291066195, 6575.342465753386, 6446.9355306448215, 6504.15445992018, 6504.707288171949, 6556.770513032021, 6558.934410656023, 6628.204615446178, 6653.600783952978, 6678.263651450755, 6680.565763225375, 6717.395434410926, 6437.549371544134, 6444.613323200623, 6454.741810327722, 6460.483161346987, 6502.4472919800455], "value": 5053.4651941874545, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [918.2057520244986, 921.3550514639752, 941.1529623338989, 943.6178347769152, 948.3598570964599, 18874.245030198792, 19458.0, 19639.0, 19736.210551577955, 19808.30670926516, 20113.59772804543, 21642.35072947814, 22001.889990550066, 22106.668399974013, 22118.33644990653, 1052.626317657234, 1074.2909679611457, 1077.9892201078007, 1078.919081068919, 1078.9946050269737, 1045.4511381524721, 1048.8951104889488, 1049.01916707878, 1049.275999560301, 1050.2228351020267, 1107.6621630402724, 1114.799336119498, 1116.3469370418316, 1116.9106471482262, 1117.3407689463215, 1113.9220254582142, 1116.7264020314994, 1116.8548088748412, 1116.9329840209614, 1117.8379135025416, 811.7280710961835, 812.6789917982424, 813.4834380168577, 814.3159745813485, 817.5666896544782, 16574.0, 16668.58328541784, 16734.49796506107, 18912.621747564972, 19102.80897191038, 21615.459613509633, 21847.563048739095, 22444.43888902774, 22454.0, 22455.0, 16721.916390418093, 16940.23768930389, 17214.7949643525, 17229.88005779633, 17372.652546949117, 3711.2020915503294, 3735.7571757835926, 3739.869104581354, 3744.363458212083, 3749.6812770914503, 117697.0, 120574.39712801468, 124564.0, 125916.0, 125969.0, 17371.91314043447, 17760.111994400228, 18049.639007219783, 18143.455696329132, 18353.0, 30524.389512210077, 31369.686303137132, 31936.0, 32126.0, 32147.67852321447, 7959.482633628795, 8312.04411492686, 8929.464232146092, 9006.964199117185, 9093.04523025073, 3969.960300396959, 4107.835686572564, 4126.236646220399, 4128.298189307825, 4133.400656904732, 5344.4922732340365, 5473.124300112043, 5473.562115030868, 5526.088195447786, 5533.253010843565, 1054.6730513540674, 1079.6383211624036, 1086.0605576176708, 1092.5848177692585, 1098.4782228441427, 694.979150625482, 713.0872483221485, 714.9918614753173, 723.706898706025, 730.5069078372078, 11555.306681599131, 11977.281363118242, 12056.51773929033, 12413.255204687748, 12946.705329466979, 686.4028295382949, 699.5383047188831, 699.8740226759232, 704.6864145455223, 708.8546847896168, 5598.543241288101, 5665.745041473144, 5673.801416950387, 5679.801206957736, 5752.13717942312, 113216.43391782988, 113764.43117784358, 114121.0, 116783.41608291904, 119665.40167299283, 93195.0, 96199.0, 98019.0, 100609.49695251478, 102007.4899625497, 86778.13221867701, 86853.0, 89762.55118724365, 90058.09941900628, 90866.0, 10490.265681402534, 10568.788624227625, 10583.31208471447, 10607.363558186533, 10851.782964340511, 39050.41424378694, 39330.0, 40265.79867100646, 40391.3941290875, 40598.39102413407, 51162.48837511726, 51164.48835511597, 51818.222726659886, 51913.22130167975, 52105.0, 15071.773923391378, 15565.533034008999, 15593.610159746097, 15735.055896646238, 15816.209189540707, 17850.0, 17925.820741792417, 18019.189136489153, 18020.459386218434, 18056.097195139926, 15068.321925513159, 15250.0, 15425.768613471031, 15428.151451670266, 15451.84548154549, 16397.59006024859, 16521.421750238685, 16529.173541322645, 16651.50045498637, 16659.91670041642, 19017.33439329617, 19206.231750729814, 19622.80377196267, 19665.21339146418, 20070.49823754418, 13769.586912392644, 14668.413263469341, 14672.633184170054, 14679.48621798232, 14702.63243418923, 15059.247037647854, 15107.848921510644, 15116.622084447978, 15183.089014659156, 15461.458848940232], "value": 9133.645370336635, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1540.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 107.0, 101.0, 107.0, 109.0, 110.0, 115.0, 104.0, 114.0, 110.0], "value": 107.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 124.0, 146.0, 184.0, 137.0, 148.0, 141.0, 121.0, 185.0, 127.0], "value": 147.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 164.0, 171.0, 164.0, 169.0, 172.0, 165.0, 166.0, 165.0], "value": 166.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 80.0, 76.0, 175.0, 75.0, 75.0, 80.0, 84.0, 168.0, 75.0], "value": 96.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 137.0, 80.0, 79.0, 79.0, 125.0, 79.0, 80.0, 129.0], "value": 94.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 119.0, 119.0, 115.0, 115.0], "value": 115.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 155.0, 151.0, 151.0, 154.0, 150.0, 150.0, 151.0, 151.0, 152.0], "value": 151.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 99.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 53.0, 54.0, 53.0, 53.0, 53.0, 53.0, 58.0, 57.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 55.0, 63.0, 55.0, 54.0, 61.0, 56.0, 56.0, 55.0], "value": 56.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 78.0, 77.0, 76.0, 102.0, 80.0, 81.0, 77.0, 85.0], "value": 81.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 122.0, 122.0, 135.0, 126.0, 123.0, 124.0, 123.0, 139.0, 169.0], "value": 130.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 175.0, 173.0, 177.0, 180.0, 171.0, 170.0, 179.0, 175.0, 170.0], "value": 175.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 71.0, 69.0, 68.0, 69.0, 67.0, 71.0, 67.0, 66.0], "value": 68.6, "unit": "score"}]}]} 04:40:59 INFO - Return code: 0 04:40:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:40:59 INFO - # TBPL SUCCESS # 04:40:59 INFO - Running post-action listener: _package_coverage_data 04:40:59 INFO - Running post-action listener: _resource_record_post_action 04:40:59 INFO - [mozharness: 2017-08-11 11:40:59.436424Z] Finished run-tests step (success) 04:40:59 INFO - Running post-run listener: _resource_record_post_run 04:40:59 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 12288; Write bytes: 435625984; Read time: 396; Write time: 321236 04:40:59 INFO - TinderboxPrint: CPU usage
13.2% 04:40:59 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 396 04:40:59 INFO - TinderboxPrint: I/O write bytes / time
435,625,984 / 321,236 04:40:59 INFO - TinderboxPrint: CPU idle
8,266.2 (86.8%) 04:40:59 INFO - TinderboxPrint: CPU user
1,202.8 (12.6%) 04:40:59 INFO - TinderboxPrint: Swap in / out
0 / 0 04:40:59 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 111214592; Read time: 0; Write time: 108984 04:40:59 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:40:59 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 8192; Write bytes: 324411392; Read time: 384; Write time: 212252 04:41:00 INFO - Running post-run listener: _upload_blobber_files 04:41:00 INFO - Blob upload gear active. 04:41:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:41:00 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 04:41:00 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'] 04:41:00 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 04:41:00 INFO - (blobuploader) - INFO - Open directory for files ... 04:41:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:41:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:41:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:41:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:41:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:41:01 INFO - (blobuploader) - INFO - Done attempting. 04:41:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:41:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:41:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:41:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:41:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:41:09 INFO - (blobuploader) - INFO - Done attempting. 04:41:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:41:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:41:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:41:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:41:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:41:10 INFO - (blobuploader) - INFO - Done attempting. 04:41:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:41:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:41:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:41:11 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:41:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:41:11 INFO - (blobuploader) - INFO - Done attempting. 04:41:11 INFO - (blobuploader) - INFO - Iteration through files over. 04:41:11 INFO - Return code: 0 04:41:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:41:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:41:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e44baf17d197928e31dd006de42c151b2a4fbbfbfb1ecf706332f0655b729fadd3142ba6eac5a494671ad3810a0359a7b885b990ad3a5602111875a5f4fa5f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65d6ea97b9c7cd94200b6bb895a886942cb81c679a0164d4e95f6999417b721e21c93e9e0ab64f68ac0964f487342399c06fd5c3d9b9edcd6988e02d4c7b9786", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c09299f4a8807c43f56a04869cbd11cc5906b32f3fd92d9bed52608818932c769872a8c1c3147a2f092f7984b5c331084be106a251f612e223a29ea3ceee368c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32a658d8c2a5332a128fecaa2e86c73225da48e925293b3b924467993586d20dc160c09d3e22beab9f52c157d0a47f59982aa4c7cf1fc379533db27b71753d21"} 04:41:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:41:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:41:11 INFO - Contents: 04:41:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e44baf17d197928e31dd006de42c151b2a4fbbfbfb1ecf706332f0655b729fadd3142ba6eac5a494671ad3810a0359a7b885b990ad3a5602111875a5f4fa5f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65d6ea97b9c7cd94200b6bb895a886942cb81c679a0164d4e95f6999417b721e21c93e9e0ab64f68ac0964f487342399c06fd5c3d9b9edcd6988e02d4c7b9786", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c09299f4a8807c43f56a04869cbd11cc5906b32f3fd92d9bed52608818932c769872a8c1c3147a2f092f7984b5c331084be106a251f612e223a29ea3ceee368c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32a658d8c2a5332a128fecaa2e86c73225da48e925293b3b924467993586d20dc160c09d3e22beab9f52c157d0a47f59982aa4c7cf1fc379533db27b71753d21"} 04:41:11 INFO - Running post-run listener: copy_logs_to_upload_dir 04:41:11 INFO - Copying logs to upload dir... 04:41:11 INFO - mkdir: /builds/slave/test/build/upload/logs 04:41:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.887303 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-11 04:41:10.534692) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-11 04:41:10.539644) ========= 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=db0483c52d987ecb441a263b000001ad-1502449052.146338-740853330 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e44baf17d197928e31dd006de42c151b2a4fbbfbfb1ecf706332f0655b729fadd3142ba6eac5a494671ad3810a0359a7b885b990ad3a5602111875a5f4fa5f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65d6ea97b9c7cd94200b6bb895a886942cb81c679a0164d4e95f6999417b721e21c93e9e0ab64f68ac0964f487342399c06fd5c3d9b9edcd6988e02d4c7b9786", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c09299f4a8807c43f56a04869cbd11cc5906b32f3fd92d9bed52608818932c769872a8c1c3147a2f092f7984b5c331084be106a251f612e223a29ea3ceee368c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32a658d8c2a5332a128fecaa2e86c73225da48e925293b3b924467993586d20dc160c09d3e22beab9f52c157d0a47f59982aa4c7cf1fc379533db27b71753d21"} build_url:https://queue.taskcluster.net/v1/task/B9z7C-sPS86eeKbhbIEBpQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011197 build_url: 'https://queue.taskcluster.net/v1/task/B9z7C-sPS86eeKbhbIEBpQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e44baf17d197928e31dd006de42c151b2a4fbbfbfb1ecf706332f0655b729fadd3142ba6eac5a494671ad3810a0359a7b885b990ad3a5602111875a5f4fa5f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65d6ea97b9c7cd94200b6bb895a886942cb81c679a0164d4e95f6999417b721e21c93e9e0ab64f68ac0964f487342399c06fd5c3d9b9edcd6988e02d4c7b9786", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c09299f4a8807c43f56a04869cbd11cc5906b32f3fd92d9bed52608818932c769872a8c1c3147a2f092f7984b5c331084be106a251f612e223a29ea3ceee368c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32a658d8c2a5332a128fecaa2e86c73225da48e925293b3b924467993586d20dc160c09d3e22beab9f52c157d0a47f59982aa4c7cf1fc379533db27b71753d21"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-11 04:41:10.569499) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 04:41:10.569881) ========= 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=db0483c52d987ecb441a263b000001ad-1502449052.146338-740853330 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004456 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 04:41:10.655163) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-11 04:41:10.655472) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-11 04:41:10.655786) ========= ========= Total master_lag: 0.21 =========