builder: mozilla-beta_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-025 starttime: 1494933574.67 results: success (0) revision: 8bf3d5c1b4b0de2673a74d56c7399a80c5431243 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-16 04:19:34.672090) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-16 04:19:34.678760) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-16 04:19:34.679109) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-16 04:19:34.787750) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 04:19:34.788049) ========= 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=232498196343f89f466a4a9a000001c8-1494933103.963941-1867037473 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-16 04:19:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 23.1M=0.001s 2017-05-16 04:19:35 (23.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.092055 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 04:19:34.897767) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 04:19:34.898067) ========= 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=232498196343f89f466a4a9a000001c8-1494933103.963941-1867037473 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019840 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 04:19:34.950024) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 04:19:34.950330) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 8bf3d5c1b4b0de2673a74d56c7399a80c5431243 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 8bf3d5c1b4b0de2673a74d56c7399a80c5431243 --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=232498196343f89f466a4a9a000001c8-1494933103.963941-1867037473 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-16 04:19:35,126 truncating revision to first 12 chars 2017-05-16 04:19:35,126 Setting DEBUG logging. 2017-05-16 04:19:35,126 attempt 1/10 2017-05-16 04:19:35,126 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/8bf3d5c1b4b0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-16 04:19:35,785 unpacking tar archive at: mozilla-beta-8bf3d5c1b4b0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.978462 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 04:19:35.944358) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-16 04:19:35.947773) ========= script_repo_revision: 8bf3d5c1b4b0de2673a74d56c7399a80c5431243 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-16 04:19:35.948339) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-16 04:19:35.948637) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-16 04:19:35.966389) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2017-05-16 04:19:35.966780) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=232498196343f89f466a4a9a000001c8-1494933103.963941-1867037473 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:19:36 INFO - MultiFileLogger online at 20170516 04:19:36 in /builds/slave/test 04:19:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO 04:19:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:19:36 INFO - {'append_to_log': False, 04:19:36 INFO - 'base_work_dir': '/builds/slave/test', 04:19:36 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 04:19:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:19:36 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 04:19:36 INFO - 'buildbot_json_path': 'buildprops.json', 04:19:36 INFO - 'config_files': ('talos/linux_config.py',), 04:19:36 INFO - 'default_actions': ('clobber', 04:19:36 INFO - 'read-buildbot-config', 04:19:36 INFO - 'download-and-extract', 04:19:36 INFO - 'populate-webroot', 04:19:36 INFO - 'create-virtualenv', 04:19:36 INFO - 'install', 04:19:36 INFO - 'run-tests'), 04:19:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:19:36 INFO - 'download_minidump_stackwalk': True, 04:19:36 INFO - 'download_symbols': 'ondemand', 04:19:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:19:36 INFO - 'tooltool.py': '/tools/tooltool.py', 04:19:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:19:36 INFO - '/tools/misc-python/virtualenv.py')}, 04:19:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:19:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:19:36 INFO - 'gecko_profile': False, 04:19:36 INFO - 'gecko_profile_interval': 0, 04:19:36 INFO - 'installer_path': 'installer.exe', 04:19:36 INFO - 'log_level': 'info', 04:19:36 INFO - 'log_name': 'talos', 04:19:36 INFO - 'log_to_console': True, 04:19:36 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:19:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:19:36 INFO - 'opt_config_files': (), 04:19:36 INFO - 'pip_index': False, 04:19:36 INFO - 'suite': 'dromaeojs', 04:19:36 INFO - 'system_bits': '32', 04:19:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:19:36 INFO - 'title': 'talos-linux64-ix-025', 04:19:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:19:36 INFO - 'use_talos_json': True, 04:19:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:19:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:19:36 INFO - 'work_dir': 'build'} 04:19:36 INFO - [mozharness: 2017-05-16 11:19:36.237942Z] Running clobber step. 04:19:36 INFO - Running pre-action listener: _resource_record_pre_action 04:19:36 INFO - Running main action method: clobber 04:19:36 INFO - rmtree: /builds/slave/test/build 04:19:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:19:37 INFO - Running post-action listener: _resource_record_post_action 04:19:37 INFO - [mozharness: 2017-05-16 11:19:37.270701Z] Finished clobber step (success) 04:19:37 INFO - [mozharness: 2017-05-16 11:19:37.270801Z] Running read-buildbot-config step. 04:19:37 INFO - Running pre-action listener: _resource_record_pre_action 04:19:37 INFO - Running main action method: read_buildbot_config 04:19:37 INFO - Using buildbot properties: 04:19:37 INFO - { 04:19:37 INFO - "project": "", 04:19:37 INFO - "product": "firefox", 04:19:37 INFO - "who": "ffxbld@noreply.mozilla.org", 04:19:37 INFO - "installer_path": "public/build/target.tar.bz2", 04:19:37 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 04:19:37 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 04:19:37 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs", 04:19:37 INFO - "stage_platform": "linux64", 04:19:37 INFO - "basedir": "/builds/slave/test", 04:19:37 INFO - "buildnumber": 45, 04:19:37 INFO - "platform": "ubuntu64_hw", 04:19:37 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 04:19:37 INFO - "slavebuilddir": "test", 04:19:37 INFO - "taskId": "Ietf9KewRq61-gDotbqBBw", 04:19:37 INFO - "branch": "mozilla-beta", 04:19:37 INFO - "script_repo_revision": "production", 04:19:37 INFO - "revision": "8bf3d5c1b4b0de2673a74d56c7399a80c5431243", 04:19:37 INFO - "slavename": "talos-linux64-ix-025", 04:19:37 INFO - "repo_path": "releases/mozilla-beta" 04:19:37 INFO - } 04:19:37 INFO - Finding installer, test and symbols from parent task. 04:19:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ietf9KewRq61-gDotbqBBw'}, attempt #1 04:19:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ietf9KewRq61-gDotbqBBw'}, attempt #1 04:19:38 INFO - Task dependencies: L-f7PxLGS6uCRwL-jYvz5g 04:19:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L-f7PxLGS6uCRwL-jYvz5g'}, attempt #1 04:19:38 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/L-f7PxLGS6uCRwL-jYvz5g/artifacts/public/build/target.tar.bz2 04:19:38 INFO - Set test_url: https://queue.taskcluster.net/v1/task/L-f7PxLGS6uCRwL-jYvz5g/artifacts/public/build/test_packages.json 04:19:38 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/L-f7PxLGS6uCRwL-jYvz5g/artifacts/public/build/target.crashreporter-symbols.zip 04:19:38 INFO - Running post-action listener: _resource_record_post_action 04:19:38 INFO - [mozharness: 2017-05-16 11:19:38.588345Z] Finished read-buildbot-config step (success) 04:19:38 INFO - [mozharness: 2017-05-16 11:19:38.588563Z] Running download-and-extract step. 04:19:38 INFO - Running pre-action listener: _resource_record_pre_action 04:19:38 INFO - Running main action method: download_and_extract 04:19:38 INFO - mkdir: /builds/slave/test/build/tests 04:19:38 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:19:38 INFO - trying https://queue.taskcluster.net/v1/task/L-f7PxLGS6uCRwL-jYvz5g/artifacts/public/build/target.test_packages.json 04:19:38 INFO - Downloading https://queue.taskcluster.net/v1/task/L-f7PxLGS6uCRwL-jYvz5g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 04:19:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L-f7PxLGS6uCRwL-jYvz5g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 04:19:39 INFO - Downloaded 986 bytes. 04:19:39 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 04:19:39 INFO - Using the following test package requirements: 04:19:39 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 04:19:39 INFO - u'common': [u'target.common.tests.zip'], 04:19:39 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 04:19:39 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 04:19:39 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 04:19:39 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 04:19:39 INFO - u'mozbase': [u'target.common.tests.zip'], 04:19:39 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 04:19:39 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 04:19:39 INFO - u'web-platform': [u'target.common.tests.zip', 04:19:39 INFO - u'target.web-platform.tests.zip'], 04:19:39 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 04:19:39 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 04:19:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/L-f7PxLGS6uCRwL-jYvz5g/artifacts/public/build/target.common.tests.zip 04:19:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L-f7PxLGS6uCRwL-jYvz5g/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:19:39 INFO - Fetch https://queue.taskcluster.net/v1/task/L-f7PxLGS6uCRwL-jYvz5g/artifacts/public/build/target.common.tests.zip into memory 04:19:40 INFO - Expected file size: 59544341 04:19:40 INFO - Obtained file size: 59544341 04:19:49 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 04:19:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/L-f7PxLGS6uCRwL-jYvz5g/artifacts/public/build/target.common.tests.zip 04:19:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L-f7PxLGS6uCRwL-jYvz5g/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:19:49 INFO - Fetch https://queue.taskcluster.net/v1/task/L-f7PxLGS6uCRwL-jYvz5g/artifacts/public/build/target.common.tests.zip into memory 04:19:50 INFO - Expected file size: 59544341 04:19:50 INFO - Obtained file size: 59544341 04:20:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/L-f7PxLGS6uCRwL-jYvz5g/artifacts/public/build/target.talos.tests.zip 04:20:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L-f7PxLGS6uCRwL-jYvz5g/artifacts/public/build/target.talos.tests.zip'}, attempt #1 04:20:01 INFO - Fetch https://queue.taskcluster.net/v1/task/L-f7PxLGS6uCRwL-jYvz5g/artifacts/public/build/target.talos.tests.zip into memory 04:20:02 INFO - Expected file size: 14404449 04:20:02 INFO - Obtained file size: 14404449 04:20: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:20:02 INFO - trying https://queue.taskcluster.net/v1/task/L-f7PxLGS6uCRwL-jYvz5g/artifacts/public/build/target.tar.bz2 04:20:02 INFO - Downloading https://queue.taskcluster.net/v1/task/L-f7PxLGS6uCRwL-jYvz5g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 04:20:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L-f7PxLGS6uCRwL-jYvz5g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 04:20:03 INFO - Downloaded 59629841 bytes. 04:20:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/L-f7PxLGS6uCRwL-jYvz5g/artifacts/public/build/target.tar.bz2 04:20:03 INFO - mkdir: /builds/slave/test/properties 04:20:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:20:03 INFO - Writing to file /builds/slave/test/properties/build_url 04:20:03 INFO - Contents: 04:20:03 INFO - build_url:https://queue.taskcluster.net/v1/task/L-f7PxLGS6uCRwL-jYvz5g/artifacts/public/build/target.tar.bz2 04:20:03 INFO - Running post-action listener: _resource_record_post_action 04:20:03 INFO - Running post-action listener: set_extra_try_arguments 04:20:03 INFO - [mozharness: 2017-05-16 11:20:03.507726Z] Finished download-and-extract step (success) 04:20:03 INFO - [mozharness: 2017-05-16 11:20:03.507823Z] Running populate-webroot step. 04:20:03 INFO - Running pre-action listener: _resource_record_pre_action 04:20:03 INFO - Running main action method: populate_webroot 04:20:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:20:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:20:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:20:03 INFO - '--tppagecycles', 04:20:03 INFO - '7'], 04:20:03 INFO - 'tests': ['tsvgm']}}, 04:20:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:20:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:20:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:20:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:20:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:20:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:20:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:20:03 INFO - 'talos_options': ['--disable-e10s'], 04:20:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:20:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:20:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:20:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:20:03 INFO - 'talos_options': ['--disable-e10s'], 04:20:03 INFO - 'tests': ['damp', 'tps']}, 04:20:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:20:03 INFO - 'tests': ['damp', 'tps']}, 04:20:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:20:03 INFO - 'tests': ['dromaeo_dom']}, 04:20:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:20:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:20:03 INFO - 'tests': ['basic_compositor_video']}, 04:20:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:20:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:20:03 INFO - 'tests': ['a11yr', 04:20:03 INFO - 'ts_paint', 04:20:03 INFO - 'tpaint', 04:20:03 INFO - 'sessionrestore', 04:20:03 INFO - 'sessionrestore_no_auto_restore', 04:20:03 INFO - 'tabpaint']}, 04:20:03 INFO - 'other-e10s': {'tests': ['a11yr', 04:20:03 INFO - 'ts_paint', 04:20:03 INFO - 'tpaint', 04:20:03 INFO - 'sessionrestore', 04:20:03 INFO - 'sessionrestore_no_auto_restore', 04:20:03 INFO - 'tabpaint']}, 04:20:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:20:03 INFO - 'ts_paint', 04:20:03 INFO - 'tpaint', 04:20:03 INFO - 'sessionrestore', 04:20:03 INFO - 'sessionrestore_no_auto_restore', 04:20:03 INFO - 'tabpaint']}, 04:20:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:20:03 INFO - 'ts_paint', 04:20:03 INFO - 'tpaint', 04:20:03 INFO - 'sessionrestore', 04:20:03 INFO - 'sessionrestore_no_auto_restore', 04:20:03 INFO - 'tabpaint']}, 04:20:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:20:03 INFO - 'tests': ['a11yr', 04:20:03 INFO - 'ts_paint', 04:20:03 INFO - 'tpaint', 04:20:03 INFO - 'sessionrestore', 04:20:03 INFO - 'sessionrestore_no_auto_restore', 04:20:03 INFO - 'tabpaint']}, 04:20:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:20:03 INFO - 'tests': ['a11yr', 04:20:03 INFO - 'ts_paint', 04:20:03 INFO - 'tpaint', 04:20:03 INFO - 'sessionrestore', 04:20:03 INFO - 'sessionrestore_no_auto_restore', 04:20:03 INFO - 'tabpaint']}, 04:20:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:20:03 INFO - 'tests': ['tsvgx', 04:20:03 INFO - 'tsvgr_opacity', 04:20:03 INFO - 'tart', 04:20:03 INFO - 'tscrollx', 04:20:03 INFO - 'cart', 04:20:03 INFO - 'tsvg_static']}, 04:20:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:20:03 INFO - 'tsvgr_opacity', 04:20:03 INFO - 'tart', 04:20:03 INFO - 'tscrollx', 04:20:03 INFO - 'cart', 04:20:03 INFO - 'tsvg_static']}, 04:20:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:20:03 INFO - 'talos_options': ['--disable-e10s'], 04:20:03 INFO - 'tests': ['tp5o']}, 04:20:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:20:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:20:03 INFO - 'talos_options': ['--disable-e10s', 04:20:03 INFO - '--xperf_path', 04:20:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:20:03 INFO - 'tests': ['tp5n']}, 04:20:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:20:03 INFO - 'talos_options': ['--xperf_path', 04:20:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:20:03 INFO - 'tests': ['tp5n']}}, 04:20:03 INFO - 'talos.zip': {'path': '', 04:20:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:20:03 INFO - Running post-action listener: _resource_record_post_action 04:20:03 INFO - [mozharness: 2017-05-16 11:20:03.520898Z] Finished populate-webroot step (success) 04:20:03 INFO - [mozharness: 2017-05-16 11:20:03.520988Z] Running create-virtualenv step. 04:20:03 INFO - Running pre-action listener: _resource_record_pre_action 04:20:03 INFO - Running main action method: create_virtualenv 04:20:03 INFO - Creating virtualenv /builds/slave/test/build/venv 04:20:03 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:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:20:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:20:03 INFO - Using real prefix '/usr' 04:20:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:20:04 INFO - Installing distribute.............................................................................................................................................................................................done. 04:20:06 INFO - Installing pip.................done. 04:20:06 INFO - Return code: 0 04:20:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:20:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:20:06 INFO - Reading from file tmpfile_stdout 04:20:06 INFO - Output received: 04:20:06 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:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:20:06 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:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:06 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:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:06 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 0x1cd31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d11a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f71310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e400>, '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 0x1f6f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6f840>, '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': '232498196343f89f466a4a9a000001c8-1494933103.963941-1867037473', '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:06 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:06 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:06 INFO - Using env: {'DISPLAY': ':0', 04:20:06 INFO - 'HOME': '/home/cltbld', 04:20:06 INFO - 'LANG': 'en_US.UTF-8', 04:20:06 INFO - 'LANGUAGE': 'en_US:en', 04:20:06 INFO - 'LOGNAME': 'cltbld', 04:20:06 INFO - 'MAIL': '/var/mail/cltbld', 04:20:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:06 INFO - 'MOZ_NO_REMOTE': '1', 04:20:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:20:06 INFO - 'NO_EM_RESTART': '1', 04:20:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:20:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:06 INFO - 'PWD': '/builds/slave/test', 04:20:06 INFO - 'SHELL': '/bin/bash', 04:20:06 INFO - 'SHLVL': '1', 04:20:06 INFO - 'TERM': 'linux', 04:20:06 INFO - 'TMOUT': '86400', 04:20:06 INFO - 'USER': 'cltbld', 04:20:06 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1494933103.963941-1867037473', 04:20:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:06 INFO - '_': '/tools/buildbot/bin/python'} 04:20:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:06 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:06 INFO - Cleaning up... 04:20:06 INFO - Return code: 0 04:20:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:20:06 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:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:06 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:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:06 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 0x1cd31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d11a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f71310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e400>, '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 0x1f6f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6f840>, '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': '232498196343f89f466a4a9a000001c8-1494933103.963941-1867037473', '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:06 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:06 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:06 INFO - Using env: {'DISPLAY': ':0', 04:20:06 INFO - 'HOME': '/home/cltbld', 04:20:06 INFO - 'LANG': 'en_US.UTF-8', 04:20:06 INFO - 'LANGUAGE': 'en_US:en', 04:20:06 INFO - 'LOGNAME': 'cltbld', 04:20:06 INFO - 'MAIL': '/var/mail/cltbld', 04:20:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:06 INFO - 'MOZ_NO_REMOTE': '1', 04:20:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:20:06 INFO - 'NO_EM_RESTART': '1', 04:20:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:20:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:06 INFO - 'PWD': '/builds/slave/test', 04:20:06 INFO - 'SHELL': '/bin/bash', 04:20:06 INFO - 'SHLVL': '1', 04:20:06 INFO - 'TERM': 'linux', 04:20:06 INFO - 'TMOUT': '86400', 04:20:06 INFO - 'USER': 'cltbld', 04:20:06 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1494933103.963941-1867037473', 04:20:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:06 INFO - '_': '/tools/buildbot/bin/python'} 04:20:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:06 INFO - Downloading/unpacking psutil>=3.1.1 04:20:06 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:06 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:06 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:06 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:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:20:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:20:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:20:07 INFO - Installing collected packages: psutil 04:20:07 INFO - Running setup.py install for psutil 04:20:08 INFO - building 'psutil._psutil_linux' extension 04:20:08 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:08 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:08 INFO - building 'psutil._psutil_posix' extension 04:20:08 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:08 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:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:20:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:20:08 INFO - Successfully installed psutil 04:20:08 INFO - Cleaning up... 04:20:08 INFO - Return code: 0 04:20:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:20:08 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:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:08 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:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:08 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 0x1cd31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d11a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f71310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e400>, '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 0x1f6f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6f840>, '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': '232498196343f89f466a4a9a000001c8-1494933103.963941-1867037473', '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:08 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:08 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:08 INFO - Using env: {'DISPLAY': ':0', 04:20:08 INFO - 'HOME': '/home/cltbld', 04:20:08 INFO - 'LANG': 'en_US.UTF-8', 04:20:08 INFO - 'LANGUAGE': 'en_US:en', 04:20:08 INFO - 'LOGNAME': 'cltbld', 04:20:08 INFO - 'MAIL': '/var/mail/cltbld', 04:20:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:08 INFO - 'MOZ_NO_REMOTE': '1', 04:20:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:20:08 INFO - 'NO_EM_RESTART': '1', 04:20:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:20:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:08 INFO - 'PWD': '/builds/slave/test', 04:20:08 INFO - 'SHELL': '/bin/bash', 04:20:08 INFO - 'SHLVL': '1', 04:20:08 INFO - 'TERM': 'linux', 04:20:08 INFO - 'TMOUT': '86400', 04:20:08 INFO - 'USER': 'cltbld', 04:20:08 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1494933103.963941-1867037473', 04:20:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:08 INFO - '_': '/tools/buildbot/bin/python'} 04:20:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:20:08 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:08 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:08 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:08 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:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:20:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:20:09 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:09 INFO - Installing collected packages: mozsystemmonitor 04:20:09 INFO - Running setup.py install for mozsystemmonitor 04:20:09 INFO - Successfully installed mozsystemmonitor 04:20:09 INFO - Cleaning up... 04:20:09 INFO - Return code: 0 04:20:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:20:09 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:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:09 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:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:09 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 0x1cd31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d11a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f71310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e400>, '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 0x1f6f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6f840>, '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': '232498196343f89f466a4a9a000001c8-1494933103.963941-1867037473', '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:09 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:09 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:09 INFO - Using env: {'DISPLAY': ':0', 04:20:09 INFO - 'HOME': '/home/cltbld', 04:20:09 INFO - 'LANG': 'en_US.UTF-8', 04:20:09 INFO - 'LANGUAGE': 'en_US:en', 04:20:09 INFO - 'LOGNAME': 'cltbld', 04:20:09 INFO - 'MAIL': '/var/mail/cltbld', 04:20:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:09 INFO - 'MOZ_NO_REMOTE': '1', 04:20:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:20:09 INFO - 'NO_EM_RESTART': '1', 04:20:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:20:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:09 INFO - 'PWD': '/builds/slave/test', 04:20:09 INFO - 'SHELL': '/bin/bash', 04:20:09 INFO - 'SHLVL': '1', 04:20:09 INFO - 'TERM': 'linux', 04:20:09 INFO - 'TMOUT': '86400', 04:20:09 INFO - 'USER': 'cltbld', 04:20:09 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1494933103.963941-1867037473', 04:20:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:09 INFO - '_': '/tools/buildbot/bin/python'} 04:20:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:10 INFO - Downloading/unpacking jsonschema==2.5.1 04:20:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:20:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:20:11 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:11 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:11 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:11 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:11 INFO - Downloading functools32-3.2.3-2.tar.gz 04:20:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:20:11 INFO - warning: no files found matching '*.txt' 04:20:11 INFO - no previously-included directories found matching 'build' 04:20:11 INFO - no previously-included directories found matching 'dist' 04:20:11 INFO - no previously-included directories found matching '.git*' 04:20:11 INFO - Installing collected packages: jsonschema, functools32 04:20:11 INFO - Running setup.py install for functools32 04:20:11 INFO - warning: no files found matching '*.txt' 04:20:11 INFO - no previously-included directories found matching 'build' 04:20:11 INFO - no previously-included directories found matching 'dist' 04:20:11 INFO - no previously-included directories found matching '.git*' 04:20:11 INFO - Successfully installed jsonschema functools32 04:20:11 INFO - Cleaning up... 04:20:11 INFO - Return code: 0 04:20:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:20:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:11 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 0x1cd31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d11a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f71310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e400>, '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 0x1f6f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6f840>, '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': '232498196343f89f466a4a9a000001c8-1494933103.963941-1867037473', '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:11 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:11 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:11 INFO - Using env: {'DISPLAY': ':0', 04:20:11 INFO - 'HOME': '/home/cltbld', 04:20:11 INFO - 'LANG': 'en_US.UTF-8', 04:20:11 INFO - 'LANGUAGE': 'en_US:en', 04:20:11 INFO - 'LOGNAME': 'cltbld', 04:20:11 INFO - 'MAIL': '/var/mail/cltbld', 04:20:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:11 INFO - 'MOZ_NO_REMOTE': '1', 04:20:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:20:11 INFO - 'NO_EM_RESTART': '1', 04:20:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:20:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:11 INFO - 'PWD': '/builds/slave/test', 04:20:11 INFO - 'SHELL': '/bin/bash', 04:20:11 INFO - 'SHLVL': '1', 04:20:11 INFO - 'TERM': 'linux', 04:20:11 INFO - 'TMOUT': '86400', 04:20:11 INFO - 'USER': 'cltbld', 04:20:11 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1494933103.963941-1867037473', 04:20:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:11 INFO - '_': '/tools/buildbot/bin/python'} 04:20:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:20:11 INFO - Cleaning up... 04:20:11 INFO - Return code: 0 04:20:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:20:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:11 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 0x1cd31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d11a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f71310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e400>, '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 0x1f6f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6f840>, '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': '232498196343f89f466a4a9a000001c8-1494933103.963941-1867037473', '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:11 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:11 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:11 INFO - Using env: {'DISPLAY': ':0', 04:20:11 INFO - 'HOME': '/home/cltbld', 04:20:11 INFO - 'LANG': 'en_US.UTF-8', 04:20:11 INFO - 'LANGUAGE': 'en_US:en', 04:20:11 INFO - 'LOGNAME': 'cltbld', 04:20:11 INFO - 'MAIL': '/var/mail/cltbld', 04:20:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:11 INFO - 'MOZ_NO_REMOTE': '1', 04:20:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:20:11 INFO - 'NO_EM_RESTART': '1', 04:20:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:20:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:11 INFO - 'PWD': '/builds/slave/test', 04:20:11 INFO - 'SHELL': '/bin/bash', 04:20:11 INFO - 'SHLVL': '1', 04:20:11 INFO - 'TERM': 'linux', 04:20:11 INFO - 'TMOUT': '86400', 04:20:11 INFO - 'USER': 'cltbld', 04:20:11 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1494933103.963941-1867037473', 04:20:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:11 INFO - '_': '/tools/buildbot/bin/python'} 04:20:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:12 INFO - Downloading/unpacking blobuploader==1.2.4 04:20:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:13 INFO - Downloading blobuploader-1.2.4.tar.gz 04:20:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:20:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:20:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:20:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:20:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:13 INFO - Downloading docopt-0.6.1.tar.gz 04:20:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:20:13 INFO - Installing collected packages: blobuploader, requests, docopt 04:20:13 INFO - Running setup.py install for blobuploader 04:20:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:20:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:20:13 INFO - Running setup.py install for requests 04:20:14 INFO - Running setup.py install for docopt 04:20:14 INFO - Successfully installed blobuploader requests docopt 04:20:14 INFO - Cleaning up... 04:20:14 INFO - Return code: 0 04:20:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:20:14 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:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:14 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:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:14 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 0x1cd31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d11a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f71310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e400>, '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 0x1f6f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6f840>, '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': '232498196343f89f466a4a9a000001c8-1494933103.963941-1867037473', '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:14 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:14 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:14 INFO - Using env: {'DISPLAY': ':0', 04:20:14 INFO - 'HOME': '/home/cltbld', 04:20:14 INFO - 'LANG': 'en_US.UTF-8', 04:20:14 INFO - 'LANGUAGE': 'en_US:en', 04:20:14 INFO - 'LOGNAME': 'cltbld', 04:20:14 INFO - 'MAIL': '/var/mail/cltbld', 04:20:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:14 INFO - 'MOZ_NO_REMOTE': '1', 04:20:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:20:14 INFO - 'NO_EM_RESTART': '1', 04:20:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:20:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:14 INFO - 'PWD': '/builds/slave/test', 04:20:14 INFO - 'SHELL': '/bin/bash', 04:20:14 INFO - 'SHLVL': '1', 04:20:14 INFO - 'TERM': 'linux', 04:20:14 INFO - 'TMOUT': '86400', 04:20:14 INFO - 'USER': 'cltbld', 04:20:14 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1494933103.963941-1867037473', 04:20:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:14 INFO - '_': '/tools/buildbot/bin/python'} 04:20:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:20:14 INFO - Running setup.py (path:/tmp/pip-CcqOO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:20:14 INFO - Running setup.py (path:/tmp/pip-bpsuXO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:20:14 INFO - Running setup.py (path:/tmp/pip-PJAdST-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:20:14 INFO - Running setup.py (path:/tmp/pip-iE2qfk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:20:14 INFO - Running setup.py (path:/tmp/pip-PR0Zkd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:20:15 INFO - Running setup.py (path:/tmp/pip-JQfNpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:20:15 INFO - Running setup.py (path:/tmp/pip-4vDSIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:20:15 INFO - Running setup.py (path:/tmp/pip-kIIz0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:20:15 INFO - Running setup.py (path:/tmp/pip-WCoUQA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:20:15 INFO - Running setup.py (path:/tmp/pip-WSnR5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:20:15 INFO - Running setup.py (path:/tmp/pip-HKpLV3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:20:15 INFO - Running setup.py (path:/tmp/pip-wOewjq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:20:15 INFO - Running setup.py (path:/tmp/pip-VELkhS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:20:15 INFO - Running setup.py (path:/tmp/pip-jui8cU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:20:15 INFO - Running setup.py (path:/tmp/pip-nPNSIW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:20:15 INFO - Running setup.py (path:/tmp/pip-4NRxAx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:20:15 INFO - Running setup.py (path:/tmp/pip-WYG7BC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:20:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:20:15 INFO - Running setup.py install for manifestparser 04:20:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:20:16 INFO - Running setup.py install for mozcrash 04:20:16 INFO - Running setup.py install for mozdebug 04:20:16 INFO - Running setup.py install for mozdevice 04:20:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:20:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:20:16 INFO - Running setup.py install for mozfile 04:20:16 INFO - Running setup.py install for mozhttpd 04:20:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:20:16 INFO - Running setup.py install for mozinfo 04:20:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:20:16 INFO - Running setup.py install for mozInstall 04:20:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:20:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:20:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:20:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:20:16 INFO - Running setup.py install for mozleak 04:20:16 INFO - Running setup.py install for mozlog 04:20:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:20:16 INFO - Running setup.py install for moznetwork 04:20:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:20:17 INFO - Running setup.py install for mozprocess 04:20:17 INFO - Running setup.py install for mozprofile 04:20:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:20:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:20:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:20:17 INFO - Running setup.py install for mozrunner 04:20:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:20:17 INFO - Running setup.py install for mozscreenshot 04:20:17 INFO - Running setup.py install for moztest 04:20:17 INFO - Running setup.py install for mozversion 04:20:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:20:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:20:17 INFO - Cleaning up... 04:20:17 INFO - Return code: 0 04:20:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 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 - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 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 - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:17 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 0x1cd31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d11a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f71310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e400>, '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 0x1f6f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6f840>, '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': '232498196343f89f466a4a9a000001c8-1494933103.963941-1867037473', '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:17 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:17 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:17 INFO - Using env: {'DISPLAY': ':0', 04:20:17 INFO - 'HOME': '/home/cltbld', 04:20:17 INFO - 'LANG': 'en_US.UTF-8', 04:20:17 INFO - 'LANGUAGE': 'en_US:en', 04:20:17 INFO - 'LOGNAME': 'cltbld', 04:20:17 INFO - 'MAIL': '/var/mail/cltbld', 04:20:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:17 INFO - 'MOZ_NO_REMOTE': '1', 04:20:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:20:17 INFO - 'NO_EM_RESTART': '1', 04:20:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:20:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:17 INFO - 'PWD': '/builds/slave/test', 04:20:17 INFO - 'SHELL': '/bin/bash', 04:20:17 INFO - 'SHLVL': '1', 04:20:17 INFO - 'TERM': 'linux', 04:20:17 INFO - 'TMOUT': '86400', 04:20:17 INFO - 'USER': 'cltbld', 04:20:17 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1494933103.963941-1867037473', 04:20:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:17 INFO - '_': '/tools/buildbot/bin/python'} 04:20:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:20:17 INFO - Running setup.py (path:/tmp/pip-pQqhqu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:20:17 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:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:20:17 INFO - Running setup.py (path:/tmp/pip-l9CnHw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:20:18 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:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:20:18 INFO - Running setup.py (path:/tmp/pip-hVQxNW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:20:18 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:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:20:18 INFO - Running setup.py (path:/tmp/pip-vfAI1c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:20:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:20:18 INFO - Running setup.py (path:/tmp/pip-DtyGrS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:20:18 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:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:20:18 INFO - Running setup.py (path:/tmp/pip-yo62Y3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:20:18 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:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:20:18 INFO - Running setup.py (path:/tmp/pip-vVaAgR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:20:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:20:18 INFO - Running setup.py (path:/tmp/pip-O2LHga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:20:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:20:18 INFO - Running setup.py (path:/tmp/pip-iioaCq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:20:18 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:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:20:18 INFO - Running setup.py (path:/tmp/pip-hygiK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:20:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:20:18 INFO - Running setup.py (path:/tmp/pip-U16LVS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:20:18 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:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:20:18 INFO - Running setup.py (path:/tmp/pip-zEg4JM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:20:18 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:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:20:18 INFO - Running setup.py (path:/tmp/pip-zWhZV5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:20:18 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:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:20:18 INFO - Running setup.py (path:/tmp/pip-VURQaN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:20:18 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:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:20:18 INFO - Running setup.py (path:/tmp/pip-soxQuJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:20:19 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:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:20:19 INFO - Running setup.py (path:/tmp/pip-pLKAl9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:20:19 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:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:20:19 INFO - Running setup.py (path:/tmp/pip-tZIL5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:20:19 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:20:19 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:20:19 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:20:19 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:20:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:20:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:20:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:20:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:20 INFO - Downloading blessings-1.6.tar.gz 04:20:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:20:20 INFO - Installing collected packages: blessings 04:20:20 INFO - Running setup.py install for blessings 04:20:20 INFO - Successfully installed blessings 04:20:20 INFO - Cleaning up... 04:20:20 INFO - Return code: 0 04:20:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:20:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:20:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:20:20 INFO - Reading from file tmpfile_stdout 04:20:20 INFO - Current package versions: 04:20:20 INFO - argparse == 1.2.1 04:20:20 INFO - blessings == 1.6 04:20:20 INFO - blobuploader == 1.2.4 04:20:20 INFO - docopt == 0.6.1 04:20:20 INFO - functools32 == 3.2.3-2 04:20:20 INFO - jsonschema == 2.5.1 04:20:20 INFO - manifestparser == 1.1 04:20:20 INFO - mozInstall == 1.12 04:20:20 INFO - mozcrash == 1.0 04:20:20 INFO - mozdebug == 0.1 04:20:20 INFO - mozdevice == 0.49 04:20:20 INFO - mozfile == 1.2 04:20:20 INFO - mozhttpd == 0.7 04:20:20 INFO - mozinfo == 0.9 04:20:20 INFO - mozleak == 0.1 04:20:20 INFO - mozlog == 3.4 04:20:20 INFO - moznetwork == 0.27 04:20:20 INFO - mozprocess == 0.25 04:20:20 INFO - mozprofile == 0.28 04:20:20 INFO - mozrunner == 6.13 04:20:20 INFO - mozscreenshot == 0.1 04:20:20 INFO - mozsystemmonitor == 0.3 04:20:20 INFO - moztest == 0.8 04:20:20 INFO - mozversion == 1.4 04:20:20 INFO - psutil == 3.1.1 04:20:20 INFO - requests == 1.2.3 04:20:20 INFO - wsgiref == 0.1.2 04:20:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:20:20 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:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:20 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:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:20 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 0x1cd31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d11a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f71310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e400>, '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 0x1f6f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6f840>, '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': '232498196343f89f466a4a9a000001c8-1494933103.963941-1867037473', '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:20 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:20:20 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:20:20 INFO - Using env: {'DISPLAY': ':0', 04:20:20 INFO - 'HOME': '/home/cltbld', 04:20:20 INFO - 'LANG': 'en_US.UTF-8', 04:20:20 INFO - 'LANGUAGE': 'en_US:en', 04:20:20 INFO - 'LOGNAME': 'cltbld', 04:20:20 INFO - 'MAIL': '/var/mail/cltbld', 04:20:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:20 INFO - 'MOZ_NO_REMOTE': '1', 04:20:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:20:20 INFO - 'NO_EM_RESTART': '1', 04:20:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:20:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:20 INFO - 'PWD': '/builds/slave/test', 04:20:20 INFO - 'SHELL': '/bin/bash', 04:20:20 INFO - 'SHLVL': '1', 04:20:20 INFO - 'TERM': 'linux', 04:20:20 INFO - 'TMOUT': '86400', 04:20:20 INFO - 'USER': 'cltbld', 04:20:20 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1494933103.963941-1867037473', 04:20:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:20 INFO - '_': '/tools/buildbot/bin/python'} 04:20:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:20 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:20:20 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:20:20 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:20:20 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:20:20 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:20:20 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:20:20 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:20:20 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:20:20 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:20:20 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:20:20 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:20:20 INFO - Cleaning up... 04:20:20 INFO - Return code: 0 04:20:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:20:20 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:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:20 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:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:20 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 0x1cd31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d11a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f71310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e400>, '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 0x1f6f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6f840>, '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': '232498196343f89f466a4a9a000001c8-1494933103.963941-1867037473', '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:20 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:20:20 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:20:20 INFO - Using env: {'DISPLAY': ':0', 04:20:20 INFO - 'HOME': '/home/cltbld', 04:20:20 INFO - 'LANG': 'en_US.UTF-8', 04:20:20 INFO - 'LANGUAGE': 'en_US:en', 04:20:20 INFO - 'LOGNAME': 'cltbld', 04:20:20 INFO - 'MAIL': '/var/mail/cltbld', 04:20:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:20 INFO - 'MOZ_NO_REMOTE': '1', 04:20:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:20:20 INFO - 'NO_EM_RESTART': '1', 04:20:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:20:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:20 INFO - 'PWD': '/builds/slave/test', 04:20:20 INFO - 'SHELL': '/bin/bash', 04:20:20 INFO - 'SHLVL': '1', 04:20:20 INFO - 'TERM': 'linux', 04:20:20 INFO - 'TMOUT': '86400', 04:20:20 INFO - 'USER': 'cltbld', 04:20:20 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1494933103.963941-1867037473', 04:20:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:20 INFO - '_': '/tools/buildbot/bin/python'} 04:20:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:20:21 INFO - Cleaning up... 04:20:21 INFO - Return code: 0 04:20:21 INFO - Running post-action listener: _resource_record_post_action 04:20:21 INFO - Running post-action listener: _start_resource_monitoring 04:20:21 INFO - Starting resource monitoring. 04:20:21 INFO - [mozharness: 2017-05-16 11:20:21.169112Z] Finished create-virtualenv step (success) 04:20:21 INFO - [mozharness: 2017-05-16 11:20:21.169652Z] Running install step. 04:20:21 INFO - Running pre-action listener: _resource_record_pre_action 04:20:21 INFO - Running main action method: install 04:20:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:20:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:20:21 INFO - Reading from file tmpfile_stdout 04:20:21 INFO - Detecting whether we're running mozinstall >=1.0... 04:20:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:20:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:20:21 INFO - Reading from file tmpfile_stdout 04:20:21 INFO - Output received: 04:20:21 INFO - Usage: mozinstall [options] installer 04:20:21 INFO - Options: 04:20:21 INFO - -h, --help show this help message and exit 04:20:21 INFO - -d DEST, --destination=DEST 04:20:21 INFO - Directory to install application into. [default: 04:20:21 INFO - "/builds/slave/test"] 04:20:21 INFO - --app=APP Application being installed. [default: firefox] 04:20:21 INFO - mkdir: /builds/slave/test/build/application 04:20:21 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:20:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 04:20:35 INFO - Reading from file tmpfile_stdout 04:20:35 INFO - Output received: 04:20:35 INFO - /builds/slave/test/build/application/firefox/firefox 04:20:35 INFO - Running post-action listener: _resource_record_post_action 04:20:35 INFO - [mozharness: 2017-05-16 11:20:35.505860Z] Finished install step (success) 04:20:35 INFO - [mozharness: 2017-05-16 11:20:35.506103Z] Running run-tests step. 04:20:35 INFO - Running pre-action listener: _resource_record_pre_action 04:20:35 INFO - Running main action method: run_tests 04:20:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:20:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:20:35 INFO - Python 2.7.3 04:20:35 INFO - Return code: 0 04:20:35 INFO - grabbing minidump binary from tooltool 04:20:35 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:35 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 0x1f6e400>, '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 0x1f6f3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6f840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:20:35 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:20:35 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:20:35 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:20:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:20:35 INFO - Return code: 0 04:20:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:20:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:20:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:20:35 INFO - ENV: RUST_BACKTRACE is now 1 04:20:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:20:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:20:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/L-f7PxLGS6uCRwL-jYvz5g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-025', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:20:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/L-f7PxLGS6uCRwL-jYvz5g/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-025 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:20:35 INFO - Using env: {'DISPLAY': ':0', 04:20:35 INFO - 'HOME': '/home/cltbld', 04:20:35 INFO - 'LANG': 'en_US.UTF-8', 04:20:35 INFO - 'LANGUAGE': 'en_US:en', 04:20:35 INFO - 'LOGNAME': 'cltbld', 04:20:35 INFO - 'MAIL': '/var/mail/cltbld', 04:20:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:20:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:20:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:35 INFO - 'MOZ_NO_REMOTE': '1', 04:20:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:20:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:20:35 INFO - 'NO_EM_RESTART': '1', 04:20:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:20:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:35 INFO - 'PWD': '/builds/slave/test', 04:20:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:20:35 INFO - 'RUST_BACKTRACE': '1', 04:20:35 INFO - 'SHELL': '/bin/bash', 04:20:35 INFO - 'SHLVL': '1', 04:20:35 INFO - 'TERM': 'linux', 04:20:35 INFO - 'TMOUT': '86400', 04:20:35 INFO - 'USER': 'cltbld', 04:20:35 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1494933103.963941-1867037473', 04:20:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:35 INFO - '_': '/tools/buildbot/bin/python'} 04:20:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/L-f7PxLGS6uCRwL-jYvz5g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-025', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:20:35 INFO - mozversion application_buildid: 20170516102106 04:20:35 INFO - mozversion application_changeset: 8bf3d5c1b4b0de2673a74d56c7399a80c5431243 04:20:35 INFO - mozversion application_display_name: Firefox 04:20:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:20:35 INFO - mozversion application_name: Firefox 04:20:35 INFO - mozversion application_remotingname: firefox 04:20:35 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 04:20:35 INFO - mozversion application_vendor: Mozilla 04:20:35 INFO - mozversion application_version: 54.0 04:20:35 INFO - mozversion platform_buildid: 20170516102106 04:20:35 INFO - mozversion platform_changeset: 8bf3d5c1b4b0de2673a74d56c7399a80c5431243 04:20:35 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 04:20:35 INFO - mozversion platform_version: 54.0 04:20:35 INFO - using testdate: 1494933635 04:20:35 INFO - actual date: 1494933635 04:20:35 INFO - starting webserver on 'localhost:40668' 04:20:35 INFO - SUITE-START | Running 2 tests 04:20:35 INFO - TEST-START | dromaeo_css 04:20:35 INFO - operating with platform_type : linux_ 04:20:35 INFO - Initialising browser for dromaeo_css test... 04:20:35 INFO - TEST-INFO | started process 2627 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcBHqLZ/profile http://localhost:40668/getInfo.html) 04:20:39 INFO - PROCESS | 2627 | __metrics Screen width/height:1600/1200 04:20:39 INFO - PROCESS | 2627 | colorDepth:24 04:20:39 INFO - PROCESS | 2627 | Browser inner width/height: 1024/697 04:20:39 INFO - PROCESS | 2627 | __metrics 04:20:40 INFO - PROCESS | 2627 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:20:40 INFO - PROCESS | 2627 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:20:41 INFO - PROCESS | 2627 | console.error: PushService: 04:20:41 INFO - PROCESS | 2627 | stateChangeProcessEnqueue: Error transitioning state 04:20:41 INFO - PROCESS | 2627 | UnknownError 04:20:41 INFO - TEST-INFO | 2627: exit 0 04:20:41 INFO - Browser initialized. 04:20:41 INFO - Running cycle 1/1 for dromaeo_css test... 04:20:41 INFO - TEST-INFO | started process 2756 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcBHqLZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:20:53 INFO - PROCESS | 2756 | RSS: Main: 140890112 04:20:53 INFO - PROCESS | 2756 | 04:23:55 INFO - PROCESS | 2756 | Cycle 1(1): loaded http://localhost:40668/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40668/tests/dromaeo/cssquery-ext.html) 04:23:56 INFO - PROCESS | 2756 | RSS: Main: 278777856 04:23:56 INFO - PROCESS | 2756 | 04:26:58 INFO - PROCESS | 2756 | Cycle 1(1): loaded http://localhost:40668/tests/dromaeo/cssquery-ext.html (next: http://localhost:40668/tests/dromaeo/cssquery-jquery.html) 04:26:59 INFO - PROCESS | 2756 | RSS: Main: 219570176 04:26:59 INFO - PROCESS | 2756 | 04:30:01 INFO - PROCESS | 2756 | Cycle 1(1): loaded http://localhost:40668/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40668/tests/dromaeo/cssquery-mootools.html) 04:30:01 INFO - PROCESS | 2756 | RSS: Main: 171630592 04:30:01 INFO - PROCESS | 2756 | 04:33:03 INFO - PROCESS | 2756 | Cycle 1(1): loaded http://localhost:40668/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40668/tests/dromaeo/cssquery-prototype.html) 04:33:04 INFO - PROCESS | 2756 | RSS: Main: 171704320 04:33:04 INFO - PROCESS | 2756 | 04:36:06 INFO - PROCESS | 2756 | Cycle 1(1): loaded http://localhost:40668/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40668/tests/dromaeo/cssquery-yui.html) 04:36:07 INFO - PROCESS | 2756 | RSS: Main: 177274880 04:36:07 INFO - PROCESS | 2756 | 04:39:10 INFO - PROCESS | 2756 | Cycle 1(1): loaded http://localhost:40668/tests/dromaeo/cssquery-yui.html (next: http://localhost:40668/tests/dromaeo/cssquery-dojo.html) 04:39:10 INFO - PROCESS | 2756 | RSS: Main: 258904064 04:39:10 INFO - PROCESS | 2756 | 04:39:10 INFO - PROCESS | 2756 | __start_tp_report 04:39:10 INFO - PROCESS | 2756 | _x_x_mozilla_page_load 04:39:10 INFO - PROCESS | 2756 | _x_x_mozilla_page_load_details 04:39:10 INFO - PROCESS | 2756 | |i|pagename|runs| 04:39:10 INFO - PROCESS | 2756 | |0;dojo.html;3422.5721784776897;3436.4158093124174;3458.67142621451;3469.68772810447;3496.37065328241;5728.169415434764;5816.098504731763;5818.098194779805;5825.766969321227;5845.941540584593;5810.883782324351;5869.266341707286;5909.172715819789;5911.261092363455;5920.733566989485;5170.896582068356;5193.376794784631;5198.480151984791;5260.579153667697;5271.657342272759;5059;5072.644914855961;5277.102892508264;5283.947160528384;5290.603204759639;5155.948440515603;5180.948190518085;5183.53348198662;5209.817656382027;5215.322008138955;5271.630985830974;5286.577073834084;5287.180487024518;5287.206918962148;5310.548603368718;4981.576565991895;5126.051680439131;5186.844394668166;5235.65968212065;5237.083510385705;5672.602917795756;5676.489115979541;5737.483626473596;5790.536757059425;5795.9420405796245;6268.122462855204;6307.653079080649;6310.87378252437;6314.084457753646;6316.526260530479;6336.112944187817;6350.587211831216;6358.586691865014;6365;6368.872622547569;5578.191162281487;5585.69278689676;5808.564357673192;5842.795502157404;5907.261592300963;6423.614583125028;6444.709988050549;6447.2907980122145;6451.096846441502;6458.2895881453005;6164.845878853064;6168.9691551541955;6169.321374648785;6172.537059720539;6173.351798061229;6013.218281623361;6059.666718330534;6179.3820617938745;6235.064740288993;6307.5269354798575;6217.378262173746;6256;6267;6267.122602835516;6294.779682711083;6007.549433792396;6018.097285407224;6028.8794224116145;6152.077188421773;6201.131841542187;4657.091867085954;4749.738764367993;4762.095201911626;4768.833090841804;4775.331453596499;4126.711130220856;4239.27932251518;4250.540301770373;4252.1920835041865;4293.119910418357;5914.9112763308585;5923.4076592340425;5955.196048533479;5984.700764961822;5999.730012149464;6318.988961766096;6362.045693146065;6362.331955144736;6362.809115726535;6364.904526432108;6047.969760151259;6054.122152287893;6059.575829691879;6063.181470501514;6063.787767428119;5085.644004919694;5112.923306150411;5245.108331583564;5266.183741520071;5361.0618141825025;6565.113709649232;6566.277709452052;6569.507286953498;6575.112359831553;6576.013597960248;6287.371262873676;6293.685315734287;6340.778072767431;6342.39747224013;6350.809475715736;6239.376062393724;6277.403646653561;6416.807495775134;6460.709268082854;6573.671316434255;6209.472194863307;6218.098375735583;6219.968900155471;6221.875562488815;6223.533235007302;6325.905111423424;6330.683465826783;6334.841628959313;6335.936640633535;6339.366063393623;5889.263842019747;5936.1689363488285;6022.247219097613;6111.602745821508;6164.753409863555;6116.15317251826;6119.694015299128;6137.039921714347;6158.168647232566;6170.969145154246;6076.665783381956;6103.023516237381;6123.877522449437;6136;6141.539384546088;5803.680797556006;5815.563832712479;5846.912296315473;6094.390560944047;6151.96924015377;6047.334793172659;6065.090236464389;6071.787487437919;6081.391860813883;6088.543359247986 04:39:10 INFO - PROCESS | 2756 | |1;ext.html;764.9961750191248;842.5408152556856;858.6522458404348;919.1543779722655;928.0997432490482;33802.83098584507;34435.827820860926;34465.31069378611;47373.526264737346;48856.26715599269;41941.79029104858;42000.369994450106;42207.57792422075;42209.15581688364;42297;9171.541422928844;9302.395344302598;9303.720888373326;9304.58129384176;9311.394759340656;7778.455508114434;7905.841883162333;10899.564017439294;11035.613753518628;11106.777864442707;11283.943580282086;11295.943520282388;11307.095432365431;11319.773604527905;11325.320480771139;11221.438928053605;11247.100231981503;11253.493592788343;11261.831072533918;11263.155263355282;10809.67570972872;10863.619773307897;10933.453327333682;10941.83587246192;10943.56225750969;43464.348034779505;43585.82070896474;43700;43734;43774;4300.376445415397;4341.9131617367475;4398.516163222043;4488.394066800973;4489.663275254369;29630.259243518867;30237.244068898235;30623.540646890313;30692;31589;3806.7715937043868;3861.3821788513983;3899.6685281751083;3945.6448919597374;3969.940450893239;233964;235567.99999999828;235649;236713;237205;47334;47464.28803567949;47494.76252618784;47536.860841261536;47544.7622761884;47849.521504784505;47884.5211547887;47972;48015.27977080346;48053.519464805606;15903.204839757966;15975.281112349972;16007.279672414536;16092;16128.516144515683;10823;13976.930115349562;14071.71856562863;14076.155430673987;14084.718305634035;11839.822402663967;12521;12670.49318027287;12806.487740490462;12820.93589532065;5299.311089558333;5392.218128371364;5465.781368745285;5519.254900588449;5549.1676248563035;1023.4524529376748;1026.6047571684805;1029.6499190275192;1044.587387981743;1045.7542477517777;30203.395932081236;30360.84819575932;30415.847920760254;30423.753358168156;30527.389452211275;988.9752756181153;1113.3320007995137;1128.2666266926562;1136.357957753883;1157.438642258504;14139.237981248876;17372.565685857953;17646.558836028944;18057.367992120213;18341.90829045873;311292;315415;315741;316897;316953;60641;60803;61092.38907610956;61130;61183.6940815302;1473.5432016075051;1545.103839772929;1545.8686011689133;1571.4578470427684;1601.175394671745;27023;29430.264243394085;29468.410631787672;29964.850175748983;30177.698223017487;38881.41677874778;39133.217335653695;39143.41284880672;39200;39225.2154956905;319425;320393;321392;321674;321879;31617.8419107903;31963.520547191347;32134.517982230755;34207;34626.48060279148;41181.382279266436;41345.379819303336;41875.37186942259;41952.58047419487;42629.786851065546;33428.1642958928;33440.1639959003;33542.49686254659;33557.83221083977;33605.32789344151;33178.33643327168;33195.336093278485;33205;33367.49948750722;33706;38124;38495.80752096334;38521.422178666784;38675.22649546937;38937.805310973265;32095.679043209268;32130.357392851543;32443.351132976735;32662.183445414055;32917.341653167285;25187;25923.870380647975;26056.8697156513;26140.954361826076;26397.60403593986 04:39:10 INFO - PROCESS | 2756 | |2;jquery.html;460.67522396710325;464.65847602012514;477.27692545793127;492.1756058601842;498.5886643519097;20102;21925.342239732814;22462.775372246273;22936.082556697733;23166.189183378585;22771.31686049417;22869.656955145645;23202.535949281046;23358.532829343403;23385.064597416127;5208.427073021964;6565.803025909218;6746.865062698743;6796.456283497333;6836.9316306837045;6530.510211734139;6675.265720770712;6677.699503522354;6721.529492935497;6733.865322693543;6694.665266736643;6721.899171512431;6745.527813053088;6746.426553742937;6747.493937954649;6509.837254068639;6674.2658307586125;6679.766208182739;6745.763898263537;6774.661266936633;6472.8705425891685;6531.150950376421;6536.738530458776;6536.869262614721;6541.0515475255825;25739.099131530307;26023.609645855326;26216.60675089875;26289.474210515684;26316.21051368462;3542.982285088558;3574.428091505373;3604.116991337139;3683.4658974448553;3717.888463346104;16036.75944860828;16788.66422671552;17098;17202.827971720373;17512.649747004987;2174.4455163933253;2232.1294695068937;2268.58031264215;2309.8267629927655;2310.7573704760925;84129.57935210346;95256.52371738166;97733;103411;106305;18027.819721802876;18586.814131858508;18803.623927521374;18825.52936176579;18963.43109706711;32535.88516746404;32622.34755304928;32687.836560817042;32701.345973080406;33060.338793224;11179.496922638338;11193.66419007431;11211.887881121247;11228.382438965935;11377.317360958368;9536.61853525865;9556.426614403159;9624.133827955378;9649.131578158;9758.804823903482;9372.765680857896;9571.760705982268;9668.226541876633;9674.56464459101;9786.5596048178;3766.6986641068647;3827.9808600956817;3843.61563843619;3934.1934903344745;3958.9911667133188;975.7999610079917;978.500964508106;981.254246772458;991.9305648604673;1002.3885429887763;21259.46851328698;21318.253861115096;21474.355769326947;21475.67786483204;21519.13923443076;990.3166814897697;1001.2590682894751;1008.455434065611;1011.8077565262577;1027.2809033676456;11316.773664526663;11927.701807454709;11941.70145746346;12014.939925300492;12057.638270851889;612658;617320;618540;618800;627411;71347.64326178336;90476.54761726149;100762.49618752005;108105;113835;1333.2933545220853;1354.878060974517;1391.8190635217356;1397.2245403800785;1400.6008287637974;18749.6250074995;18906.243750249836;19191.136398861807;19274.61450771005;19373.709394358815;52325;53005.46994530159;53073.734631326595;53855.192172116665;54197.45802541924;69366;69705.3029469719;69827.6508617474;70306.64846675734;70561.29438705547;12019.699507512381;12138.817917731065;12381.442835072623;12402.13185077036;12452.750944981231;13298.40157192948;13305.933470332586;13315.201087934754;13406;13621.523246686318;11323.490442930253;11413.828792568283;11760.88304105235;12218.87781122213;12253.142280040312;11180;11337.433128343386;11376.431178441211;11501;11530.365829879438;11674.124440666814;11805.645830625095;12701.87298127007;12851.16467429614;13236.536721214712;12505.93747031259;12531.12282140278;12638.747225055631;12639.747205056032;12747.171433856769;12323.383830808603;12409.131360804657;12551.560695375983;12869.09916305887;12876.935615321863 04:39:10 INFO - PROCESS | 2756 | |3;mootools.html;1565.6477292609156;1580.1783072802143;1593.9123348215846;1608.9275982580782;1629.9755503667448;11098.500567474463;11834.644960651194;11845.703857403569;11855.822162667568;11862.466189021494;11178.32930024199;11422.885771142286;11441.256318339301;11448.885511144887;11587.42062896856;2548.7068987066446;2584.2893204368856;2591.714911359749;2592.948141037178;2593.7146913839556;2440.424777700668;2497.7876880465183;2520.18094119411;2522.7603377679093;2528.582783840664;2376.4534157143867;2426;2500.762427569378;2510.221831232324;2510.585753350704;2387.283814855554;2428.9514209715794;2430.1373012580457;2433.5011322678806;2441.2554170977846;2373.4778348763243;2392.210570511727;2394.772496612819;2397.2328854766483;2404.831661783676;11371;11644.59243926467;11653.184277100607;11653.825192622118;11654.067674586013;5869.530437564985;5991.460768530809;6008.429199226067;6009.699515024231;6011.068284415923;3406.5912090549045;3470.410030294856;3543.4330507118993;3561.51919490868;3597.9820100899587;1249.244207254616;1272.7581759465672;1273.5924504158672;1294.3528235882059;1351.047511504387;14454;14796.852031479762;16200.27098780546;16309.347626094943;16374.83625163757;4943.307936888838;5083.313752643346;5163.586913046947;5246.108161612535;5283.313169287968;11630.244034137753;11750.29498230109;13309.201447912963;14336.856631433553;14346.928265358607;6093.390660933871;6242.375762423721;6256.343083976117;6256.7810126645345;6258.030005349178;5572.442755724395;5637.46444087811;5687.857803554862;5690.118031705092;5749.68376739275;4464.17412778637;4469.597736203757;4550.112728018071;4574.108048930427;4594.402727645384;1245.0786418050577;1284.319310765296;1284.6274580371773;1298.4806077569049;1298.8831005209577;1064.4837253931837;1094.3433939636154;1095.64939219449;1100.4607742206422;1104.9337039777638;7163.713451461883;7174.174969878426;7231.45764067697;7300.56196628204;7336.6522712732485;1022.5960745505483;1024.3188279794003;1030.9639162629271;1036.7822757220918;1053.5680371047833;4657.790399431966;4721;4721.268203428474;4940.728259945738;4976.825811096666;13973.510927117502;14446.133232006114;14586.416543338148;14900.850991489946;15318.693626127639;14530.05554638945;14676.926615367069;14753.631159221106;14760;14763.704725905422;10440.477976101316;10513.57945682164;10615.416152111708;10670.62652807148;10695.893041069645;7463.552186868806;7695.384369250559;7697.384209263358;7708.961455192688;7801.375889928906;13043.413046412745;13127.146735462164;13152;13204.80192797128;13486.86513134856;13343.399547020601;13402.530911418053;13489.730205396034;13491.257980810757;13569.118007329493;9755.902440975784;10166;10327.741806454598;10335.638252661121;10346.844797328196;11405.486753096297;11553.422328883691;11561.479733412185;11567.942160289145;11623.6512904613;9648.565814538222;9672.75818104553;9723;9934.304598678023;9950.203983681144;9367.484788336707;9386.812263754824;9460.479673618018;9476.715698529055;9533.523323833919;8859.246964007874;8953.641854325753;8964.193222609996;9022.368434209542;9060.501672408082;6355.237371515448;6397.520185985976;6400.327965563735;6443.516736244894;6535.57518761298;6649.567778094409;6724.361185687352;6730.259671436236;6871.03805467225;6889.965550172217 04:39:10 INFO - PROCESS | 2756 | |4;prototype.html;418.5960548071111;443.43683521927164;443.6761164350024;446.20798083401957;448.16193717747814;5163.115462856059;5228.241904923784;5255.92116118258;5271.446498117691;5280.419153893078;4933.235348520976;5148.510891465305;5162.793488260465;5166.354205724285;5186.247994040871;4220.134872351161;4248.787560621972;4252.8511502097435;4265.445492086024;4284.378765079062;4286.97856510717;4289.978550107245;4291.35629655551;4294.506131794852;4306.633936115419;4268.701190916637;4270.572942705736;4286.97856510717;4290.377895205193;4309.836344187068;4111.629953304202;4232.830686772525;4253.914921701579;4294.549072347421;4306.74159550428;4044.170944956277;4060.248853961997;4063.207674503473;4068.877933661995;4070.1859628074208;5053.620978426633;5105.438401775802;5111.207762796773;5119.488051194888;5127.5385215330425;4787.712737235777;4928.186849169882;4944.53026962438;4961.305417241589;5059.924101138486;5445.428230035829;5455.918161227585;5460.399356070829;5479.726013699299;5488.91766623501;4474.910501789946;4490.618297444721;4545.272756359;4549.203889319389;4552.135094332067;5354.759035843396;5586.832395028156;5611.961787069406;5622.269105016322;5674.687892165929;4646.465656449484;4652.116097941381;4661.930071048937;4665.6967297125575;4689.155951928686;4740.715557066587;4740.905181896412;4784.712917224978;4793.760311984387;4797.856064318076;4909.727109819964;4945.332380128709;5038.168702164102;5048.217526283432;5053.534475002281;5394.815099884553;5406.59450541211;5421.566274698014;5427.55759908043;5435.864103397446;1606.9276882540314;1616.3696158498194;1620.4490473239155;1625.7317542605338;1655.9751603725954;1203.8796120388067;1261.9684507887375;1275.7943743162807;1283.6983308922395;1291.6244199927048;5131.358580177478;5360.383555891122;5370.409254981949;5395.298611180521;5418.8916221675345;5512.200730893998;5535.252740880011;5553.777848885999;5569;5583.497485226349;5341.2878049755045;5345.625806193529;5405.405405405402;5407;5439.537639300704;2775.500409926233;2922.9269268268463;2986.7759918006236;2990.551417287424;3000.634711206396;5727.940331038772;5762.855928601734;5772.624779389326;5774.68239246837;5787.855303617359;5504.394516603171;5565.888682226333;5697.316322041382;5704.743286552115;5707.74315155819;5625.887482250332;5642.843217140558;5773.220615216892;5803.113987953931;5820.912686309794;5597.5930349948285;5615.466530679579;5657.32112146545;5663.461971112738;5691.459311365414;5450.155225939986;5478.260434841405;5508.394076651645;5526.972365138148;5540.722963851872;5684.772609095755;5722.141678748221;5730.140478928194;5754.913676294943;5783.3638299787835;4815.3499277597075;4929.728864912464;5050.3434553507805;5059.406287019577;5079.568236699922;5535.695536745357;5586.38549759518;5588.15590855025;5591.916121258103;5613.663180209201;5487.533559647474;5492.489565369556;5494.615376923577;5507.339119305709;5513.338399392099;5307.4692530746615;5409.537895746804;5449.809256675997;5512.118061110203;5522.282103326542;5474.534664553398;5481.917771233514;5493.8299371748735;5529.419410961791;5531.9723401382735;5498.230247765396;5506.697131657797;5542.334919809648;5547.778088876561;5592.217089607377;5196.402413722319;5290.312259406406;5353.384360798558;5387.164989426645;5423.755930983037 04:39:10 INFO - PROCESS | 2756 | |5;yui.html;859.7291853066288;896.4755617963496;920.7099763574473;922.0871337376;932.6383480119026;17470.73793893091;17544.210510527024;17584.29662813486;18147.727784083232;19102.90448547757;16178.029318240913;16203.351865925379;17434.3026278949;18297.17662705178;18515.0742462877;899.5816945120501;925.4956048953337;931.1340453378356;931.7903471718877;931.9906800931972;897.1477096758073;912.0332447605526;919.0258326174243;926.2173463423396;927.7819712367589;961.1157734883889;963.3160456076195;965.0590674092773;965.2471072563412;965.7344230336644;960.4237457525498;962.210986990668;963.3689933093834;964.0744884910495;964.1997142371849;701.7122979578348;703.2756261051126;705.6789160931763;707.5860621536356;707.628495040103;17177.82822171787;17323.48029559115;17410.21654025572;17978.190981405867;18155.72766408505;14916.925415372854;16400;16568;16779.32882684691;17235;15214.847851521565;15291.235438228045;15325.770113448307;15415.3833846646;15482.922585387114;4915.311856340115;4931.901361972777;4966.925496117561;4967.900641987176;5064.518870707277;51868;53134.46865531372;69294;69882.9517557242;95530.0446995535;11870.762584748429;11889.643310700692;11923.773870447385;12133.817992730115;12141.271523708429;21246.681299780517;21598.89200553987;21744.891275543836;24553.877230613733;24813.379665508146;9019.729408117766;9174.86237706435;9398.624055037859;9419.481928493999;9627.133557979816;4861.465238823726;5009.323741294878;5017.623678224148;5042.168042273057;5125.15434953236;3803.087259057806;3919.8824035278985;3924.0778417071965;4184.309588917856;4360.280553708602;742.8142964258935;747.4991755523812;747.9027726395642;758.5714071549556;762.7139822566537;766.7124828189429;773.4353921637124;778.1362687416963;781.5115552779513;787.4605894961854;11016.724581885517;11266.493007814668;11763.529458821551;12182.451789669487;12517;710.8684893294757;712.5688958180226;729.1760310848813;730.7150211417552;731.9670614822239;5532.529734972572;5784.132380143013;5951.196588460502;5952.940470595326;6008.399160083956;79147;79705;80274;82253.17746822575;84519.57740211382;36573;50962.74518627383;73086.269137309;73344;75457.62271188719;40969.590304097175;55583.72208138934;62027.689861551306;62075.689621552505;62872;11753.294802311888;11915.344656044;11972.521099155936;12230.327331996825;12232.571859984857;44309.33535997027;44602.77698611616;45060;45289.32066018946;45682;43705.34441983309;43988.12023759443;49741.253881191085;50823.745881270355;51329;14516.709665806837;14616.196109214095;14754.704905901606;14758.704825903207;14774.18741969202;15706.2932168055;17173.57066073358;17552;18512.352067677566;18747.718784217974;14802.777958330418;15328.080315181125;15901;16009.67980640404;16605.08672023002;15341.156236407105;16176;16228.594285142493;16664;16722.749158762872;15987.200639967723;16134.757978630565;16300.592485187964;16394.672106557562;16420.75368869444;10951.233413661286;11210.831837522606;11405;11568.595099171489;12000.879991199976;12373;13093.541726039542;13145.145565538209;13151.73696526045;13320.866791331962 04:39:10 INFO - PROCESS | 2756 | __end_tp_report 04:39:10 INFO - PROCESS | 2756 | __start_cc_report 04:39:10 INFO - PROCESS | 2756 | _x_x_mozilla_cycle_collect,1393 04:39:10 INFO - PROCESS | 2756 | __end_cc_report 04:39:10 INFO - PROCESS | 2756 | __startTimestamp1494934750402__endTimestamp 04:39:10 INFO - PROCESS | 2756 | 04:39:10 INFO - PROCESS | 2756 | ------- Summary: start ------- 04:39:10 INFO - PROCESS | 2756 | Number of tests: 6 04:39:10 INFO - PROCESS | 2756 | 04:39:10 INFO - PROCESS | 2756 | [#0] dojo.html Cycles:165 Average:5792.10 Median:6054.12 stddev:682.60 (11.3%) stddev-sans-first:658.90 04:39:10 INFO - PROCESS | 2756 | Values: 3422.6 3436.4 3458.7 3469.7 3496.4 5728.2 5816.1 5818.1 5825.8 5845.9 5810.9 5869.3 5909.2 5911.3 5920.7 5170.9 5193.4 5198.5 5260.6 5271.7 5059.0 5072.6 5277.1 5283.9 5290.6 5155.9 5180.9 5183.5 5209.8 5215.3 5271.6 5286.6 5287.2 5287.2 5310.5 4981.6 5126.1 5186.8 5235.7 5237.1 5672.6 5676.5 5737.5 5790.5 5795.9 6268.1 6307.7 6310.9 6314.1 6316.5 6336.1 6350.6 6358.6 6365.0 6368.9 5578.2 5585.7 5808.6 5842.8 5907.3 6423.6 6444.7 6447.3 6451.1 6458.3 6164.8 6169.0 6169.3 6172.5 6173.4 6013.2 6059.7 6179.4 6235.1 6307.5 6217.4 6256.0 6267.0 6267.1 6294.8 6007.5 6018.1 6028.9 6152.1 6201.1 4657.1 4749.7 4762.1 4768.8 4775.3 4126.7 4239.3 4250.5 4252.2 4293.1 5914.9 5923.4 5955.2 5984.7 5999.7 6319.0 6362.0 6362.3 6362.8 6364.9 6048.0 6054.1 6059.6 6063.2 6063.8 5085.6 5112.9 5245.1 5266.2 5361.1 6565.1 6566.3 6569.5 6575.1 6576.0 6287.4 6293.7 6340.8 6342.4 6350.8 6239.4 6277.4 6416.8 6460.7 6573.7 6209.5 6218.1 6220.0 6221.9 6223.5 6325.9 6330.7 6334.8 6335.9 6339.4 5889.3 5936.2 6022.2 6111.6 6164.8 6116.2 6119.7 6137.0 6158.2 6171.0 6076.7 6103.0 6123.9 6136.0 6141.5 5803.7 5815.6 5846.9 6094.4 6152.0 6047.3 6065.1 6071.8 6081.4 6088.5 04:39:10 INFO - PROCESS | 2756 | 04:39:10 INFO - PROCESS | 2756 | [#1] ext.html Cycles:180 Average:45878.04 Median:321776.50 stddev:76564.19 (23.8%) stddev-sans-first:76704.05 04:39:10 INFO - PROCESS | 2756 | Values: 765.0 842.5 858.7 919.2 928.1 33802.8 34435.8 34465.3 47373.5 48856.3 41941.8 42000.4 42207.6 42209.2 42297.0 9171.5 9302.4 9303.7 9304.6 9311.4 7778.5 7905.8 10899.6 11035.6 11106.8 11283.9 11295.9 11307.1 11319.8 11325.3 11221.4 11247.1 11253.5 11261.8 11263.2 10809.7 10863.6 10933.5 10941.8 10943.6 43464.3 43585.8 43700.0 43734.0 43774.0 4300.4 4341.9 4398.5 4488.4 4489.7 29630.3 30237.2 30623.5 30692.0 31589.0 3806.8 3861.4 3899.7 3945.6 3969.9 233964.0 235568.0 235649.0 236713.0 237205.0 47334.0 47464.3 47494.8 47536.9 47544.8 47849.5 47884.5 47972.0 48015.3 48053.5 15903.2 15975.3 16007.3 16092.0 16128.5 10823.0 13976.9 14071.7 14076.2 14084.7 11839.8 12521.0 12670.5 12806.5 12820.9 5299.3 5392.2 5465.8 5519.3 5549.2 1023.5 1026.6 1029.6 1044.6 1045.8 30203.4 30360.8 30415.8 30423.8 30527.4 989.0 1113.3 1128.3 1136.4 1157.4 14139.2 17372.6 17646.6 18057.4 18341.9 311292.0 315415.0 315741.0 316897.0 316953.0 60641.0 60803.0 61092.4 61130.0 61183.7 1473.5 1545.1 1545.9 1571.5 1601.2 27023.0 29430.3 29468.4 29964.9 30177.7 38881.4 39133.2 39143.4 39200.0 39225.2 319425.0 320393.0 321392.0 321674.0 321879.0 31617.8 31963.5 32134.5 34207.0 34626.5 41181.4 41345.4 41875.4 41952.6 42629.8 33428.2 33440.2 33542.5 33557.8 33605.3 33178.3 33195.3 33205.0 33367.5 33706.0 38124.0 38495.8 38521.4 38675.2 38937.8 32095.7 32130.4 32443.4 32662.2 32917.3 25187.0 25923.9 26056.9 26141.0 26397.6 04:39:10 INFO - PROCESS | 2756 | 04:39:10 INFO - PROCESS | 2756 | [#2] jquery.html Cycles:180 Average:35891.48 Median:12738.47 stddev:101613.52 (797.7%) stddev-sans-first:101863.74 04:39:10 INFO - PROCESS | 2756 | Values: 460.7 464.7 477.3 492.2 498.6 20102.0 21925.3 22462.8 22936.1 23166.2 22771.3 22869.7 23202.5 23358.5 23385.1 5208.4 6565.8 6746.9 6796.5 6836.9 6530.5 6675.3 6677.7 6721.5 6733.9 6694.7 6721.9 6745.5 6746.4 6747.5 6509.8 6674.3 6679.8 6745.8 6774.7 6472.9 6531.2 6536.7 6536.9 6541.1 25739.1 26023.6 26216.6 26289.5 26316.2 3543.0 3574.4 3604.1 3683.5 3717.9 16036.8 16788.7 17098.0 17202.8 17512.6 2174.4 2232.1 2268.6 2309.8 2310.8 84129.6 95256.5 97733.0 103411.0 106305.0 18027.8 18586.8 18803.6 18825.5 18963.4 32535.9 32622.3 32687.8 32701.3 33060.3 11179.5 11193.7 11211.9 11228.4 11377.3 9536.6 9556.4 9624.1 9649.1 9758.8 9372.8 9571.8 9668.2 9674.6 9786.6 3766.7 3828.0 3843.6 3934.2 3959.0 975.8 978.5 981.3 991.9 1002.4 21259.5 21318.3 21474.4 21475.7 21519.1 990.3 1001.3 1008.5 1011.8 1027.3 11316.8 11927.7 11941.7 12014.9 12057.6 612658.0 617320.0 618540.0 618800.0 627411.0 71347.6 90476.5 100762.5 108105.0 113835.0 1333.3 1354.9 1391.8 1397.2 1400.6 18749.6 18906.2 19191.1 19274.6 19373.7 52325.0 53005.5 53073.7 53855.2 54197.5 69366.0 69705.3 69827.7 70306.6 70561.3 12019.7 12138.8 12381.4 12402.1 12452.8 13298.4 13305.9 13315.2 13406.0 13621.5 11323.5 11413.8 11760.9 12218.9 12253.1 11180.0 11337.4 11376.4 11501.0 11530.4 11674.1 11805.6 12701.9 12851.2 13236.5 12505.9 12531.1 12638.7 12639.7 12747.2 12323.4 12409.1 12551.6 12869.1 12876.9 04:39:10 INFO - PROCESS | 2756 | 04:39:10 INFO - PROCESS | 2756 | [#3] mootools.html Cycles:180 Average:7293.55 Median:2415.42 stddev:4518.96 (187.1%) stddev-sans-first:4511.14 04:39:10 INFO - PROCESS | 2756 | Values: 1565.6 1580.2 1593.9 1608.9 1630.0 11098.5 11834.6 11845.7 11855.8 11862.5 11178.3 11422.9 11441.3 11448.9 11587.4 2548.7 2584.3 2591.7 2592.9 2593.7 2440.4 2497.8 2520.2 2522.8 2528.6 2376.5 2426.0 2500.8 2510.2 2510.6 2387.3 2429.0 2430.1 2433.5 2441.3 2373.5 2392.2 2394.8 2397.2 2404.8 11371.0 11644.6 11653.2 11653.8 11654.1 5869.5 5991.5 6008.4 6009.7 6011.1 3406.6 3470.4 3543.4 3561.5 3598.0 1249.2 1272.8 1273.6 1294.4 1351.0 14454.0 14796.9 16200.3 16309.3 16374.8 4943.3 5083.3 5163.6 5246.1 5283.3 11630.2 11750.3 13309.2 14336.9 14346.9 6093.4 6242.4 6256.3 6256.8 6258.0 5572.4 5637.5 5687.9 5690.1 5749.7 4464.2 4469.6 4550.1 4574.1 4594.4 1245.1 1284.3 1284.6 1298.5 1298.9 1064.5 1094.3 1095.6 1100.5 1104.9 7163.7 7174.2 7231.5 7300.6 7336.7 1022.6 1024.3 1031.0 1036.8 1053.6 4657.8 4721.0 4721.3 4940.7 4976.8 13973.5 14446.1 14586.4 14900.9 15318.7 14530.1 14676.9 14753.6 14760.0 14763.7 10440.5 10513.6 10615.4 10670.6 10695.9 7463.6 7695.4 7697.4 7709.0 7801.4 13043.4 13127.1 13152.0 13204.8 13486.9 13343.4 13402.5 13489.7 13491.3 13569.1 9755.9 10166.0 10327.7 10335.6 10346.8 11405.5 11553.4 11561.5 11567.9 11623.7 9648.6 9672.8 9723.0 9934.3 9950.2 9367.5 9386.8 9460.5 9476.7 9533.5 8859.2 8953.6 8964.2 9022.4 9060.5 6355.2 6397.5 6400.3 6443.5 6535.6 6649.6 6724.4 6730.3 6871.0 6890.0 04:39:10 INFO - PROCESS | 2756 | 04:39:10 INFO - PROCESS | 2756 | [#4] prototype.html Cycles:180 Average:4760.44 Median:5275.93 stddev:1266.29 (24.0%) stddev-sans-first:1227.19 04:39:10 INFO - PROCESS | 2756 | Values: 418.6 443.4 443.7 446.2 448.2 5163.1 5228.2 5255.9 5271.4 5280.4 4933.2 5148.5 5162.8 5166.4 5186.2 4220.1 4248.8 4252.9 4265.4 4284.4 4287.0 4290.0 4291.4 4294.5 4306.6 4268.7 4270.6 4287.0 4290.4 4309.8 4111.6 4232.8 4253.9 4294.5 4306.7 4044.2 4060.2 4063.2 4068.9 4070.2 5053.6 5105.4 5111.2 5119.5 5127.5 4787.7 4928.2 4944.5 4961.3 5059.9 5445.4 5455.9 5460.4 5479.7 5488.9 4474.9 4490.6 4545.3 4549.2 4552.1 5354.8 5586.8 5612.0 5622.3 5674.7 4646.5 4652.1 4661.9 4665.7 4689.2 4740.7 4740.9 4784.7 4793.8 4797.9 4909.7 4945.3 5038.2 5048.2 5053.5 5394.8 5406.6 5421.6 5427.6 5435.9 1606.9 1616.4 1620.4 1625.7 1656.0 1203.9 1262.0 1275.8 1283.7 1291.6 5131.4 5360.4 5370.4 5395.3 5418.9 5512.2 5535.3 5553.8 5569.0 5583.5 5341.3 5345.6 5405.4 5407.0 5439.5 2775.5 2922.9 2986.8 2990.6 3000.6 5727.9 5762.9 5772.6 5774.7 5787.9 5504.4 5565.9 5697.3 5704.7 5707.7 5625.9 5642.8 5773.2 5803.1 5820.9 5597.6 5615.5 5657.3 5663.5 5691.5 5450.2 5478.3 5508.4 5527.0 5540.7 5684.8 5722.1 5730.1 5754.9 5783.4 4815.3 4929.7 5050.3 5059.4 5079.6 5535.7 5586.4 5588.2 5591.9 5613.7 5487.5 5492.5 5494.6 5507.3 5513.3 5307.5 5409.5 5449.8 5512.1 5522.3 5474.5 5481.9 5493.8 5529.4 5532.0 5498.2 5506.7 5542.3 5547.8 5592.2 5196.4 5290.3 5353.4 5387.2 5423.8 04:39:10 INFO - PROCESS | 2756 | 04:39:10 INFO - PROCESS | 2756 | [#5] yui.html Cycles:180 Average:17930.45 Median:44831.39 stddev:20976.55 (46.8%) stddev-sans-first:20996.22 04:39:10 INFO - PROCESS | 2756 | Values: 859.7 896.5 920.7 922.1 932.6 17470.7 17544.2 17584.3 18147.7 19102.9 16178.0 16203.4 17434.3 18297.2 18515.1 899.6 925.5 931.1 931.8 932.0 897.1 912.0 919.0 926.2 927.8 961.1 963.3 965.1 965.2 965.7 960.4 962.2 963.4 964.1 964.2 701.7 703.3 705.7 707.6 707.6 17177.8 17323.5 17410.2 17978.2 18155.7 14916.9 16400.0 16568.0 16779.3 17235.0 15214.8 15291.2 15325.8 15415.4 15482.9 4915.3 4931.9 4966.9 4967.9 5064.5 51868.0 53134.5 69294.0 69883.0 95530.0 11870.8 11889.6 11923.8 12133.8 12141.3 21246.7 21598.9 21744.9 24553.9 24813.4 9019.7 9174.9 9398.6 9419.5 9627.1 4861.5 5009.3 5017.6 5042.2 5125.2 3803.1 3919.9 3924.1 4184.3 4360.3 742.8 747.5 747.9 758.6 762.7 766.7 773.4 778.1 781.5 787.5 11016.7 11266.5 11763.5 12182.5 12517.0 710.9 712.6 729.2 730.7 732.0 5532.5 5784.1 5951.2 5952.9 6008.4 79147.0 79705.0 80274.0 82253.2 84519.6 36573.0 50962.7 73086.3 73344.0 75457.6 40969.6 55583.7 62027.7 62075.7 62872.0 11753.3 11915.3 11972.5 12230.3 12232.6 44309.3 44602.8 45060.0 45289.3 45682.0 43705.3 43988.1 49741.3 50823.7 51329.0 14516.7 14616.2 14754.7 14758.7 14774.2 15706.3 17173.6 17552.0 18512.4 18747.7 14802.8 15328.1 15901.0 16009.7 16605.1 15341.2 16176.0 16228.6 16664.0 16722.7 15987.2 16134.8 16300.6 16394.7 16420.8 10951.2 11210.8 11405.0 11568.6 12000.9 12373.0 13093.5 13145.1 13151.7 13320.9 04:39:10 INFO - PROCESS | 2756 | -------- Summary: end -------- 04:39:10 INFO - PROCESS | 2756 | 04:39:11 INFO - TEST-INFO | 2756: exit 0 04:39:11 INFO - TEST-OK | dromaeo_css | took 1115464ms 04:39:11 INFO - TEST-START | kraken 04:39:11 INFO - operating with platform_type : linux_ 04:39:11 INFO - Initialising browser for kraken test... 04:39:11 INFO - TEST-INFO | started process 3127 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDJR0qz/profile http://localhost:40668/getInfo.html) 04:39:15 INFO - PROCESS | 3127 | __metrics Screen width/height:1600/1200 04:39:15 INFO - PROCESS | 3127 | colorDepth:24 04:39:15 INFO - PROCESS | 3127 | Browser inner width/height: 1024/697 04:39:15 INFO - PROCESS | 3127 | __metrics 04:39:15 INFO - PROCESS | 3127 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:39:15 INFO - PROCESS | 3127 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:39:16 INFO - TEST-INFO | 3127: exit 0 04:39:16 INFO - Browser initialized. 04:39:16 INFO - Running cycle 1/1 for kraken test... 04:39:17 INFO - TEST-INFO | started process 3219 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDJR0qz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:39:28 INFO - PROCESS | 3219 | RSS: Main: 142049280 04:39:28 INFO - PROCESS | 3219 | 04:39:31 INFO - PROCESS | 3219 | Cycle 1(1): loaded http://localhost:40668/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40668/tests/kraken/driver.html?testName=audio-beat-detection) 04:39:32 INFO - PROCESS | 3219 | RSS: Main: 184176640 04:39:32 INFO - PROCESS | 3219 | 04:39:34 INFO - PROCESS | 3219 | Cycle 1(1): loaded http://localhost:40668/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40668/tests/kraken/driver.html?testName=audio-dft) 04:39:35 INFO - PROCESS | 3219 | RSS: Main: 174342144 04:39:35 INFO - PROCESS | 3219 | 04:39:38 INFO - PROCESS | 3219 | Cycle 1(1): loaded http://localhost:40668/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40668/tests/kraken/driver.html?testName=audio-fft) 04:39:38 INFO - PROCESS | 3219 | RSS: Main: 265891840 04:39:38 INFO - PROCESS | 3219 | 04:39:40 INFO - PROCESS | 3219 | Cycle 1(1): loaded http://localhost:40668/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40668/tests/kraken/driver.html?testName=audio-oscillator) 04:39:41 INFO - PROCESS | 3219 | RSS: Main: 171380736 04:39:41 INFO - PROCESS | 3219 | 04:39:43 INFO - PROCESS | 3219 | Cycle 1(1): loaded http://localhost:40668/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40668/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:39:44 INFO - PROCESS | 3219 | RSS: Main: 174522368 04:39:44 INFO - PROCESS | 3219 | 04:39:46 INFO - PROCESS | 3219 | Cycle 1(1): loaded http://localhost:40668/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40668/tests/kraken/driver.html?testName=imaging-darkroom) 04:39:47 INFO - PROCESS | 3219 | RSS: Main: 213565440 04:39:47 INFO - PROCESS | 3219 | 04:39:50 INFO - PROCESS | 3219 | Cycle 1(1): loaded http://localhost:40668/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40668/tests/kraken/driver.html?testName=imaging-desaturate) 04:39:51 INFO - PROCESS | 3219 | RSS: Main: 213524480 04:39:51 INFO - PROCESS | 3219 | 04:39:54 INFO - PROCESS | 3219 | Cycle 1(1): loaded http://localhost:40668/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40668/tests/kraken/driver.html?testName=json-parse-financial) 04:39:54 INFO - PROCESS | 3219 | RSS: Main: 212271104 04:39:54 INFO - PROCESS | 3219 | 04:39:56 INFO - PROCESS | 3219 | Cycle 1(1): loaded http://localhost:40668/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40668/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:39:56 INFO - PROCESS | 3219 | RSS: Main: 174407680 04:39:56 INFO - PROCESS | 3219 | 04:39:58 INFO - PROCESS | 3219 | Cycle 1(1): loaded http://localhost:40668/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40668/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:39:59 INFO - PROCESS | 3219 | RSS: Main: 181432320 04:39:59 INFO - PROCESS | 3219 | 04:40:01 INFO - PROCESS | 3219 | Cycle 1(1): loaded http://localhost:40668/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40668/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:40:01 INFO - PROCESS | 3219 | RSS: Main: 190623744 04:40:01 INFO - PROCESS | 3219 | 04:40:04 INFO - PROCESS | 3219 | Cycle 1(1): loaded http://localhost:40668/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40668/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:40:05 INFO - PROCESS | 3219 | RSS: Main: 199024640 04:40:05 INFO - PROCESS | 3219 | 04:40:07 INFO - PROCESS | 3219 | Cycle 1(1): loaded http://localhost:40668/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40668/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:40:08 INFO - PROCESS | 3219 | RSS: Main: 195694592 04:40:08 INFO - PROCESS | 3219 | 04:40:09 INFO - PROCESS | 3219 | Cycle 1(1): loaded http://localhost:40668/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40668/tests/kraken/driver.html?testName=ai-astar) 04:40:10 INFO - PROCESS | 3219 | RSS: Main: 247414784 04:40:10 INFO - PROCESS | 3219 | 04:40:10 INFO - PROCESS | 3219 | __start_tp_report 04:40:10 INFO - PROCESS | 3219 | _x_x_mozilla_page_load 04:40:10 INFO - PROCESS | 3219 | _x_x_mozilla_page_load_details 04:40:10 INFO - PROCESS | 3219 | |i|pagename|runs| 04:40:10 INFO - PROCESS | 3219 | |0;ai-astar;104;113;113;112;114;113;112;114;113;113 04:40:10 INFO - PROCESS | 3219 | |1;audio-beat-detection;146;118;114;155;116;117;117;116;137;120 04:40:10 INFO - PROCESS | 3219 | |2;audio-dft;160;160;162;161;161;161;161;161;160;161 04:40:10 INFO - PROCESS | 3219 | |3;audio-fft;74;76;75;102;76;76;75;75;91;74 04:40:10 INFO - PROCESS | 3219 | |4;audio-oscillator;81;83;100;82;82;83;104;83;82;98 04:40:10 INFO - PROCESS | 3219 | |5;imaging-gaussian-blur;113;114;113;113;113;112;113;112;113;113 04:40:10 INFO - PROCESS | 3219 | |6;imaging-darkroom;146;146;147;146;147;147;147;146;147;146 04:40:10 INFO - PROCESS | 3219 | |7;imaging-desaturate;95;94;94;93;94;94;94;93;94;93 04:40:10 INFO - PROCESS | 3219 | |8;json-parse-financial;54;55;53;54;53;54;54;54;54;53 04:40:10 INFO - PROCESS | 3219 | |9;json-stringify-tinderbox;52;53;54;53;53;53;52;53;53;53 04:40:10 INFO - PROCESS | 3219 | |10;stanford-crypto-aes;76;77;76;80;80;76;81;81;80;77 04:40:10 INFO - PROCESS | 3219 | |11;stanford-crypto-ccm;122;121;119;120;123;126;127;118;181;121 04:40:10 INFO - PROCESS | 3219 | |12;stanford-crypto-pbkdf2;157;158;160;157;156;155;155;156;157;158 04:40:10 INFO - PROCESS | 3219 | |13;stanford-crypto-sha256-iterative;61;59;63;59;60;62;58;58;59;58 04:40:10 INFO - PROCESS | 3219 | __end_tp_report 04:40:10 INFO - PROCESS | 3219 | __start_cc_report 04:40:10 INFO - PROCESS | 3219 | _x_x_mozilla_cycle_collect,1642 04:40:10 INFO - PROCESS | 3219 | __end_cc_report 04:40:10 INFO - PROCESS | 3219 | __startTimestamp1494934810221__endTimestamp 04:40:10 INFO - PROCESS | 3219 | 04:40:10 INFO - PROCESS | 3219 | ------- Summary: start ------- 04:40:10 INFO - PROCESS | 3219 | Number of tests: 14 04:40:10 INFO - PROCESS | 3219 | 04:40:10 INFO - PROCESS | 3219 | [#0] ai-astar Cycles:10 Average:112.10 Median:113.00 stddev:2.92 (2.6%) stddev-sans-first:0.71 04:40:10 INFO - PROCESS | 3219 | Values: 104.0 113.0 113.0 112.0 114.0 113.0 112.0 114.0 113.0 113.0 04:40:10 INFO - PROCESS | 3219 | 04:40:10 INFO - PROCESS | 3219 | [#1] audio-beat-detection Cycles:10 Average:125.60 Median:119.00 stddev:14.78 (12.4%) stddev-sans-first:13.71 04:40:10 INFO - PROCESS | 3219 | Values: 146.0 118.0 114.0 155.0 116.0 117.0 117.0 116.0 137.0 120.0 04:40:10 INFO - PROCESS | 3219 | 04:40:10 INFO - PROCESS | 3219 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 04:40:10 INFO - PROCESS | 3219 | Values: 160.0 160.0 162.0 161.0 161.0 161.0 161.0 161.0 160.0 161.0 04:40:10 INFO - PROCESS | 3219 | 04:40:10 INFO - PROCESS | 3219 | [#3] audio-fft Cycles:10 Average:79.40 Median:75.50 stddev:9.41 (12.5%) stddev-sans-first:9.77 04:40:10 INFO - PROCESS | 3219 | Values: 74.0 76.0 75.0 102.0 76.0 76.0 75.0 75.0 91.0 74.0 04:40:10 INFO - PROCESS | 3219 | 04:40:10 INFO - PROCESS | 3219 | [#4] audio-oscillator Cycles:10 Average:87.80 Median:82.50 stddev:9.02 (10.9%) stddev-sans-first:9.22 04:40:10 INFO - PROCESS | 3219 | Values: 81.0 83.0 100.0 82.0 82.0 83.0 104.0 83.0 82.0 98.0 04:40:10 INFO - PROCESS | 3219 | 04:40:10 INFO - PROCESS | 3219 | [#5] imaging-gaussian-blur Cycles:10 Average:112.90 Median:113.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 04:40:10 INFO - PROCESS | 3219 | Values: 113.0 114.0 113.0 113.0 113.0 112.0 113.0 112.0 113.0 113.0 04:40:10 INFO - PROCESS | 3219 | 04:40:10 INFO - PROCESS | 3219 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:147.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 04:40:10 INFO - PROCESS | 3219 | Values: 146.0 146.0 147.0 146.0 147.0 147.0 147.0 146.0 147.0 146.0 04:40:10 INFO - PROCESS | 3219 | 04:40:10 INFO - PROCESS | 3219 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.50 04:40:10 INFO - PROCESS | 3219 | Values: 95.0 94.0 94.0 93.0 94.0 94.0 94.0 93.0 94.0 93.0 04:40:10 INFO - PROCESS | 3219 | 04:40:10 INFO - PROCESS | 3219 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:54.00 stddev:0.63 (1.2%) stddev-sans-first:0.67 04:40:10 INFO - PROCESS | 3219 | Values: 54.0 55.0 53.0 54.0 53.0 54.0 54.0 54.0 54.0 53.0 04:40:10 INFO - PROCESS | 3219 | 04:40:10 INFO - PROCESS | 3219 | [#9] json-stringify-tinderbox Cycles:10 Average:52.90 Median:53.00 stddev:0.57 (1.1%) stddev-sans-first:0.50 04:40:10 INFO - PROCESS | 3219 | Values: 52.0 53.0 54.0 53.0 53.0 53.0 52.0 53.0 53.0 53.0 04:40:10 INFO - PROCESS | 3219 | 04:40:10 INFO - PROCESS | 3219 | [#10] stanford-crypto-aes Cycles:10 Average:78.40 Median:80.00 stddev:2.17 (2.7%) stddev-sans-first:2.12 04:40:10 INFO - PROCESS | 3219 | Values: 76.0 77.0 76.0 80.0 80.0 76.0 81.0 81.0 80.0 77.0 04:40:10 INFO - PROCESS | 3219 | 04:40:10 INFO - PROCESS | 3219 | [#11] stanford-crypto-ccm Cycles:10 Average:127.80 Median:122.50 stddev:18.91 (15.4%) stddev-sans-first:19.94 04:40:10 INFO - PROCESS | 3219 | Values: 122.0 121.0 119.0 120.0 123.0 126.0 127.0 118.0 181.0 121.0 04:40:10 INFO - PROCESS | 3219 | 04:40:10 INFO - PROCESS | 3219 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.90 Median:157.00 stddev:1.52 (1.0%) stddev-sans-first:1.62 04:40:10 INFO - PROCESS | 3219 | Values: 157.0 158.0 160.0 157.0 156.0 155.0 155.0 156.0 157.0 158.0 04:40:10 INFO - PROCESS | 3219 | 04:40:10 INFO - PROCESS | 3219 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.70 Median:59.50 stddev:1.77 (3.0%) stddev-sans-first:1.81 04:40:10 INFO - PROCESS | 3219 | Values: 61.0 59.0 63.0 59.0 60.0 62.0 58.0 58.0 59.0 58.0 04:40:10 INFO - PROCESS | 3219 | -------- Summary: end -------- 04:40:10 INFO - PROCESS | 3219 | 04:40:11 INFO - TEST-INFO | 3219: exit 0 04:40:11 INFO - TEST-OK | kraken | took 60094ms 04:40:11 INFO - SUITE-END | took 1175s 04:40:11 INFO - Completed test suite (00:19:35) 04:40:11 INFO - javascript benchmark 04:40:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7927.576783183358, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3422.5721784776897, 3436.4158093124174, 3458.67142621451, 3469.68772810447, 3496.37065328241, 5728.169415434764, 5816.098504731763, 5818.098194779805, 5825.766969321227, 5845.941540584593, 5810.883782324351, 5869.266341707286, 5909.172715819789, 5911.261092363455, 5920.733566989485, 5170.896582068356, 5193.376794784631, 5198.480151984791, 5260.579153667697, 5271.657342272759, 5059.0, 5072.644914855961, 5277.102892508264, 5283.947160528384, 5290.603204759639, 5155.948440515603, 5180.948190518085, 5183.53348198662, 5209.817656382027, 5215.322008138955, 5271.630985830974, 5286.577073834084, 5287.180487024518, 5287.206918962148, 5310.548603368718, 4981.576565991895, 5126.051680439131, 5186.844394668166, 5235.65968212065, 5237.083510385705, 5672.602917795756, 5676.489115979541, 5737.483626473596, 5790.536757059425, 5795.9420405796245, 6268.122462855204, 6307.653079080649, 6310.87378252437, 6314.084457753646, 6316.526260530479, 6336.112944187817, 6350.587211831216, 6358.586691865014, 6365.0, 6368.872622547569, 5578.191162281487, 5585.69278689676, 5808.564357673192, 5842.795502157404, 5907.261592300963, 6423.614583125028, 6444.709988050549, 6447.2907980122145, 6451.096846441502, 6458.2895881453005, 6164.845878853064, 6168.9691551541955, 6169.321374648785, 6172.537059720539, 6173.351798061229, 6013.218281623361, 6059.666718330534, 6179.3820617938745, 6235.064740288993, 6307.5269354798575, 6217.378262173746, 6256.0, 6267.0, 6267.122602835516, 6294.779682711083, 6007.549433792396, 6018.097285407224, 6028.8794224116145, 6152.077188421773, 6201.131841542187, 4657.091867085954, 4749.738764367993, 4762.095201911626, 4768.833090841804, 4775.331453596499, 4126.711130220856, 4239.27932251518, 4250.540301770373, 4252.1920835041865, 4293.119910418357, 5914.9112763308585, 5923.4076592340425, 5955.196048533479, 5984.700764961822, 5999.730012149464, 6318.988961766096, 6362.045693146065, 6362.331955144736, 6362.809115726535, 6364.904526432108, 6047.969760151259, 6054.122152287893, 6059.575829691879, 6063.181470501514, 6063.787767428119, 5085.644004919694, 5112.923306150411, 5245.108331583564, 5266.183741520071, 5361.0618141825025, 6565.113709649232, 6566.277709452052, 6569.507286953498, 6575.112359831553, 6576.013597960248, 6287.371262873676, 6293.685315734287, 6340.778072767431, 6342.39747224013, 6350.809475715736, 6239.376062393724, 6277.403646653561, 6416.807495775134, 6460.709268082854, 6573.671316434255, 6209.472194863307, 6218.098375735583, 6219.968900155471, 6221.875562488815, 6223.533235007302, 6325.905111423424, 6330.683465826783, 6334.841628959313, 6335.936640633535, 6339.366063393623, 5889.263842019747, 5936.1689363488285, 6022.247219097613, 6111.602745821508, 6164.753409863555, 6116.15317251826, 6119.694015299128, 6137.039921714347, 6158.168647232566, 6170.969145154246, 6076.665783381956, 6103.023516237381, 6123.877522449437, 6136.0, 6141.539384546088, 5803.680797556006, 5815.563832712479, 5846.912296315473, 6094.390560944047, 6151.96924015377, 6047.334793172659, 6065.090236464389, 6071.787487437919, 6081.391860813883, 6088.543359247986], "value": 5745.49578963973, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [764.9961750191248, 842.5408152556856, 858.6522458404348, 919.1543779722655, 928.0997432490482, 33802.83098584507, 34435.827820860926, 34465.31069378611, 47373.526264737346, 48856.26715599269, 41941.79029104858, 42000.369994450106, 42207.57792422075, 42209.15581688364, 42297.0, 9171.541422928844, 9302.395344302598, 9303.720888373326, 9304.58129384176, 9311.394759340656, 7778.455508114434, 7905.841883162333, 10899.564017439294, 11035.613753518628, 11106.777864442707, 11283.943580282086, 11295.943520282388, 11307.095432365431, 11319.773604527905, 11325.320480771139, 11221.438928053605, 11247.100231981503, 11253.493592788343, 11261.831072533918, 11263.155263355282, 10809.67570972872, 10863.619773307897, 10933.453327333682, 10941.83587246192, 10943.56225750969, 43464.348034779505, 43585.82070896474, 43700.0, 43734.0, 43774.0, 4300.376445415397, 4341.9131617367475, 4398.516163222043, 4488.394066800973, 4489.663275254369, 29630.259243518867, 30237.244068898235, 30623.540646890313, 30692.0, 31589.0, 3806.7715937043868, 3861.3821788513983, 3899.6685281751083, 3945.6448919597374, 3969.940450893239, 233964.0, 235567.99999999828, 235649.0, 236713.0, 237205.0, 47334.0, 47464.28803567949, 47494.76252618784, 47536.860841261536, 47544.7622761884, 47849.521504784505, 47884.5211547887, 47972.0, 48015.27977080346, 48053.519464805606, 15903.204839757966, 15975.281112349972, 16007.279672414536, 16092.0, 16128.516144515683, 10823.0, 13976.930115349562, 14071.71856562863, 14076.155430673987, 14084.718305634035, 11839.822402663967, 12521.0, 12670.49318027287, 12806.487740490462, 12820.93589532065, 5299.311089558333, 5392.218128371364, 5465.781368745285, 5519.254900588449, 5549.1676248563035, 1023.4524529376748, 1026.6047571684805, 1029.6499190275192, 1044.587387981743, 1045.7542477517777, 30203.395932081236, 30360.84819575932, 30415.847920760254, 30423.753358168156, 30527.389452211275, 988.9752756181153, 1113.3320007995137, 1128.2666266926562, 1136.357957753883, 1157.438642258504, 14139.237981248876, 17372.565685857953, 17646.558836028944, 18057.367992120213, 18341.90829045873, 311292.0, 315415.0, 315741.0, 316897.0, 316953.0, 60641.0, 60803.0, 61092.38907610956, 61130.0, 61183.6940815302, 1473.5432016075051, 1545.103839772929, 1545.8686011689133, 1571.4578470427684, 1601.175394671745, 27023.0, 29430.264243394085, 29468.410631787672, 29964.850175748983, 30177.698223017487, 38881.41677874778, 39133.217335653695, 39143.41284880672, 39200.0, 39225.2154956905, 319425.0, 320393.0, 321392.0, 321674.0, 321879.0, 31617.8419107903, 31963.520547191347, 32134.517982230755, 34207.0, 34626.48060279148, 41181.382279266436, 41345.379819303336, 41875.37186942259, 41952.58047419487, 42629.786851065546, 33428.1642958928, 33440.1639959003, 33542.49686254659, 33557.83221083977, 33605.32789344151, 33178.33643327168, 33195.336093278485, 33205.0, 33367.49948750722, 33706.0, 38124.0, 38495.80752096334, 38521.422178666784, 38675.22649546937, 38937.805310973265, 32095.679043209268, 32130.357392851543, 32443.351132976735, 32662.183445414055, 32917.341653167285, 25187.0, 25923.870380647975, 26056.8697156513, 26140.954361826076, 26397.60403593986], "value": 18987.40095622922, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [460.67522396710325, 464.65847602012514, 477.27692545793127, 492.1756058601842, 498.5886643519097, 20102.0, 21925.342239732814, 22462.775372246273, 22936.082556697733, 23166.189183378585, 22771.31686049417, 22869.656955145645, 23202.535949281046, 23358.532829343403, 23385.064597416127, 5208.427073021964, 6565.803025909218, 6746.865062698743, 6796.456283497333, 6836.9316306837045, 6530.510211734139, 6675.265720770712, 6677.699503522354, 6721.529492935497, 6733.865322693543, 6694.665266736643, 6721.899171512431, 6745.527813053088, 6746.426553742937, 6747.493937954649, 6509.837254068639, 6674.2658307586125, 6679.766208182739, 6745.763898263537, 6774.661266936633, 6472.8705425891685, 6531.150950376421, 6536.738530458776, 6536.869262614721, 6541.0515475255825, 25739.099131530307, 26023.609645855326, 26216.60675089875, 26289.474210515684, 26316.21051368462, 3542.982285088558, 3574.428091505373, 3604.116991337139, 3683.4658974448553, 3717.888463346104, 16036.75944860828, 16788.66422671552, 17098.0, 17202.827971720373, 17512.649747004987, 2174.4455163933253, 2232.1294695068937, 2268.58031264215, 2309.8267629927655, 2310.7573704760925, 84129.57935210346, 95256.52371738166, 97733.0, 103411.0, 106305.0, 18027.819721802876, 18586.814131858508, 18803.623927521374, 18825.52936176579, 18963.43109706711, 32535.88516746404, 32622.34755304928, 32687.836560817042, 32701.345973080406, 33060.338793224, 11179.496922638338, 11193.66419007431, 11211.887881121247, 11228.382438965935, 11377.317360958368, 9536.61853525865, 9556.426614403159, 9624.133827955378, 9649.131578158, 9758.804823903482, 9372.765680857896, 9571.760705982268, 9668.226541876633, 9674.56464459101, 9786.5596048178, 3766.6986641068647, 3827.9808600956817, 3843.61563843619, 3934.1934903344745, 3958.9911667133188, 975.7999610079917, 978.500964508106, 981.254246772458, 991.9305648604673, 1002.3885429887763, 21259.46851328698, 21318.253861115096, 21474.355769326947, 21475.67786483204, 21519.13923443076, 990.3166814897697, 1001.2590682894751, 1008.455434065611, 1011.8077565262577, 1027.2809033676456, 11316.773664526663, 11927.701807454709, 11941.70145746346, 12014.939925300492, 12057.638270851889, 612658.0, 617320.0, 618540.0, 618800.0, 627411.0, 71347.64326178336, 90476.54761726149, 100762.49618752005, 108105.0, 113835.0, 1333.2933545220853, 1354.878060974517, 1391.8190635217356, 1397.2245403800785, 1400.6008287637974, 18749.6250074995, 18906.243750249836, 19191.136398861807, 19274.61450771005, 19373.709394358815, 52325.0, 53005.46994530159, 53073.734631326595, 53855.192172116665, 54197.45802541924, 69366.0, 69705.3029469719, 69827.6508617474, 70306.64846675734, 70561.29438705547, 12019.699507512381, 12138.817917731065, 12381.442835072623, 12402.13185077036, 12452.750944981231, 13298.40157192948, 13305.933470332586, 13315.201087934754, 13406.0, 13621.523246686318, 11323.490442930253, 11413.828792568283, 11760.88304105235, 12218.87781122213, 12253.142280040312, 11180.0, 11337.433128343386, 11376.431178441211, 11501.0, 11530.365829879438, 11674.124440666814, 11805.645830625095, 12701.87298127007, 12851.16467429614, 13236.536721214712, 12505.93747031259, 12531.12282140278, 12638.747225055631, 12639.747205056032, 12747.171433856769, 12323.383830808603, 12409.131360804657, 12551.560695375983, 12869.09916305887, 12876.935615321863], "value": 11539.12204250835, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1565.6477292609156, 1580.1783072802143, 1593.9123348215846, 1608.9275982580782, 1629.9755503667448, 11098.500567474463, 11834.644960651194, 11845.703857403569, 11855.822162667568, 11862.466189021494, 11178.32930024199, 11422.885771142286, 11441.256318339301, 11448.885511144887, 11587.42062896856, 2548.7068987066446, 2584.2893204368856, 2591.714911359749, 2592.948141037178, 2593.7146913839556, 2440.424777700668, 2497.7876880465183, 2520.18094119411, 2522.7603377679093, 2528.582783840664, 2376.4534157143867, 2426.0, 2500.762427569378, 2510.221831232324, 2510.585753350704, 2387.283814855554, 2428.9514209715794, 2430.1373012580457, 2433.5011322678806, 2441.2554170977846, 2373.4778348763243, 2392.210570511727, 2394.772496612819, 2397.2328854766483, 2404.831661783676, 11371.0, 11644.59243926467, 11653.184277100607, 11653.825192622118, 11654.067674586013, 5869.530437564985, 5991.460768530809, 6008.429199226067, 6009.699515024231, 6011.068284415923, 3406.5912090549045, 3470.410030294856, 3543.4330507118993, 3561.51919490868, 3597.9820100899587, 1249.244207254616, 1272.7581759465672, 1273.5924504158672, 1294.3528235882059, 1351.047511504387, 14454.0, 14796.852031479762, 16200.27098780546, 16309.347626094943, 16374.83625163757, 4943.307936888838, 5083.313752643346, 5163.586913046947, 5246.108161612535, 5283.313169287968, 11630.244034137753, 11750.29498230109, 13309.201447912963, 14336.856631433553, 14346.928265358607, 6093.390660933871, 6242.375762423721, 6256.343083976117, 6256.7810126645345, 6258.030005349178, 5572.442755724395, 5637.46444087811, 5687.857803554862, 5690.118031705092, 5749.68376739275, 4464.17412778637, 4469.597736203757, 4550.112728018071, 4574.108048930427, 4594.402727645384, 1245.0786418050577, 1284.319310765296, 1284.6274580371773, 1298.4806077569049, 1298.8831005209577, 1064.4837253931837, 1094.3433939636154, 1095.64939219449, 1100.4607742206422, 1104.9337039777638, 7163.713451461883, 7174.174969878426, 7231.45764067697, 7300.56196628204, 7336.6522712732485, 1022.5960745505483, 1024.3188279794003, 1030.9639162629271, 1036.7822757220918, 1053.5680371047833, 4657.790399431966, 4721.0, 4721.268203428474, 4940.728259945738, 4976.825811096666, 13973.510927117502, 14446.133232006114, 14586.416543338148, 14900.850991489946, 15318.693626127639, 14530.05554638945, 14676.926615367069, 14753.631159221106, 14760.0, 14763.704725905422, 10440.477976101316, 10513.57945682164, 10615.416152111708, 10670.62652807148, 10695.893041069645, 7463.552186868806, 7695.384369250559, 7697.384209263358, 7708.961455192688, 7801.375889928906, 13043.413046412745, 13127.146735462164, 13152.0, 13204.80192797128, 13486.86513134856, 13343.399547020601, 13402.530911418053, 13489.730205396034, 13491.257980810757, 13569.118007329493, 9755.902440975784, 10166.0, 10327.741806454598, 10335.638252661121, 10346.844797328196, 11405.486753096297, 11553.422328883691, 11561.479733412185, 11567.942160289145, 11623.6512904613, 9648.565814538222, 9672.75818104553, 9723.0, 9934.304598678023, 9950.203983681144, 9367.484788336707, 9386.812263754824, 9460.479673618018, 9476.715698529055, 9533.523323833919, 8859.246964007874, 8953.641854325753, 8964.193222609996, 9022.368434209542, 9060.501672408082, 6355.237371515448, 6397.520185985976, 6400.327965563735, 6443.516736244894, 6535.57518761298, 6649.567778094409, 6724.361185687352, 6730.259671436236, 6871.03805467225, 6889.965550172217], "value": 5565.92397060376, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [418.5960548071111, 443.43683521927164, 443.6761164350024, 446.20798083401957, 448.16193717747814, 5163.115462856059, 5228.241904923784, 5255.92116118258, 5271.446498117691, 5280.419153893078, 4933.235348520976, 5148.510891465305, 5162.793488260465, 5166.354205724285, 5186.247994040871, 4220.134872351161, 4248.787560621972, 4252.8511502097435, 4265.445492086024, 4284.378765079062, 4286.97856510717, 4289.978550107245, 4291.35629655551, 4294.506131794852, 4306.633936115419, 4268.701190916637, 4270.572942705736, 4286.97856510717, 4290.377895205193, 4309.836344187068, 4111.629953304202, 4232.830686772525, 4253.914921701579, 4294.549072347421, 4306.74159550428, 4044.170944956277, 4060.248853961997, 4063.207674503473, 4068.877933661995, 4070.1859628074208, 5053.620978426633, 5105.438401775802, 5111.207762796773, 5119.488051194888, 5127.5385215330425, 4787.712737235777, 4928.186849169882, 4944.53026962438, 4961.305417241589, 5059.924101138486, 5445.428230035829, 5455.918161227585, 5460.399356070829, 5479.726013699299, 5488.91766623501, 4474.910501789946, 4490.618297444721, 4545.272756359, 4549.203889319389, 4552.135094332067, 5354.759035843396, 5586.832395028156, 5611.961787069406, 5622.269105016322, 5674.687892165929, 4646.465656449484, 4652.116097941381, 4661.930071048937, 4665.6967297125575, 4689.155951928686, 4740.715557066587, 4740.905181896412, 4784.712917224978, 4793.760311984387, 4797.856064318076, 4909.727109819964, 4945.332380128709, 5038.168702164102, 5048.217526283432, 5053.534475002281, 5394.815099884553, 5406.59450541211, 5421.566274698014, 5427.55759908043, 5435.864103397446, 1606.9276882540314, 1616.3696158498194, 1620.4490473239155, 1625.7317542605338, 1655.9751603725954, 1203.8796120388067, 1261.9684507887375, 1275.7943743162807, 1283.6983308922395, 1291.6244199927048, 5131.358580177478, 5360.383555891122, 5370.409254981949, 5395.298611180521, 5418.8916221675345, 5512.200730893998, 5535.252740880011, 5553.777848885999, 5569.0, 5583.497485226349, 5341.2878049755045, 5345.625806193529, 5405.405405405402, 5407.0, 5439.537639300704, 2775.500409926233, 2922.9269268268463, 2986.7759918006236, 2990.551417287424, 3000.634711206396, 5727.940331038772, 5762.855928601734, 5772.624779389326, 5774.68239246837, 5787.855303617359, 5504.394516603171, 5565.888682226333, 5697.316322041382, 5704.743286552115, 5707.74315155819, 5625.887482250332, 5642.843217140558, 5773.220615216892, 5803.113987953931, 5820.912686309794, 5597.5930349948285, 5615.466530679579, 5657.32112146545, 5663.461971112738, 5691.459311365414, 5450.155225939986, 5478.260434841405, 5508.394076651645, 5526.972365138148, 5540.722963851872, 5684.772609095755, 5722.141678748221, 5730.140478928194, 5754.913676294943, 5783.3638299787835, 4815.3499277597075, 4929.728864912464, 5050.3434553507805, 5059.406287019577, 5079.568236699922, 5535.695536745357, 5586.38549759518, 5588.15590855025, 5591.916121258103, 5613.663180209201, 5487.533559647474, 5492.489565369556, 5494.615376923577, 5507.339119305709, 5513.338399392099, 5307.4692530746615, 5409.537895746804, 5449.809256675997, 5512.118061110203, 5522.282103326542, 5474.534664553398, 5481.917771233514, 5493.8299371748735, 5529.419410961791, 5531.9723401382735, 5498.230247765396, 5506.697131657797, 5542.334919809648, 5547.778088876561, 5592.217089607377, 5196.402413722319, 5290.312259406406, 5353.384360798558, 5387.164989426645, 5423.755930983037], "value": 4398.174999635506, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [859.7291853066288, 896.4755617963496, 920.7099763574473, 922.0871337376, 932.6383480119026, 17470.73793893091, 17544.210510527024, 17584.29662813486, 18147.727784083232, 19102.90448547757, 16178.029318240913, 16203.351865925379, 17434.3026278949, 18297.17662705178, 18515.0742462877, 899.5816945120501, 925.4956048953337, 931.1340453378356, 931.7903471718877, 931.9906800931972, 897.1477096758073, 912.0332447605526, 919.0258326174243, 926.2173463423396, 927.7819712367589, 961.1157734883889, 963.3160456076195, 965.0590674092773, 965.2471072563412, 965.7344230336644, 960.4237457525498, 962.210986990668, 963.3689933093834, 964.0744884910495, 964.1997142371849, 701.7122979578348, 703.2756261051126, 705.6789160931763, 707.5860621536356, 707.628495040103, 17177.82822171787, 17323.48029559115, 17410.21654025572, 17978.190981405867, 18155.72766408505, 14916.925415372854, 16400.0, 16568.0, 16779.32882684691, 17235.0, 15214.847851521565, 15291.235438228045, 15325.770113448307, 15415.3833846646, 15482.922585387114, 4915.311856340115, 4931.901361972777, 4966.925496117561, 4967.900641987176, 5064.518870707277, 51868.0, 53134.46865531372, 69294.0, 69882.9517557242, 95530.0446995535, 11870.762584748429, 11889.643310700692, 11923.773870447385, 12133.817992730115, 12141.271523708429, 21246.681299780517, 21598.89200553987, 21744.891275543836, 24553.877230613733, 24813.379665508146, 9019.729408117766, 9174.86237706435, 9398.624055037859, 9419.481928493999, 9627.133557979816, 4861.465238823726, 5009.323741294878, 5017.623678224148, 5042.168042273057, 5125.15434953236, 3803.087259057806, 3919.8824035278985, 3924.0778417071965, 4184.309588917856, 4360.280553708602, 742.8142964258935, 747.4991755523812, 747.9027726395642, 758.5714071549556, 762.7139822566537, 766.7124828189429, 773.4353921637124, 778.1362687416963, 781.5115552779513, 787.4605894961854, 11016.724581885517, 11266.493007814668, 11763.529458821551, 12182.451789669487, 12517.0, 710.8684893294757, 712.5688958180226, 729.1760310848813, 730.7150211417552, 731.9670614822239, 5532.529734972572, 5784.132380143013, 5951.196588460502, 5952.940470595326, 6008.399160083956, 79147.0, 79705.0, 80274.0, 82253.17746822575, 84519.57740211382, 36573.0, 50962.74518627383, 73086.269137309, 73344.0, 75457.62271188719, 40969.590304097175, 55583.72208138934, 62027.689861551306, 62075.689621552505, 62872.0, 11753.294802311888, 11915.344656044, 11972.521099155936, 12230.327331996825, 12232.571859984857, 44309.33535997027, 44602.77698611616, 45060.0, 45289.32066018946, 45682.0, 43705.34441983309, 43988.12023759443, 49741.253881191085, 50823.745881270355, 51329.0, 14516.709665806837, 14616.196109214095, 14754.704905901606, 14758.704825903207, 14774.18741969202, 15706.2932168055, 17173.57066073358, 17552.0, 18512.352067677566, 18747.718784217974, 14802.777958330418, 15328.080315181125, 15901.0, 16009.67980640404, 16605.08672023002, 15341.156236407105, 16176.0, 16228.594285142493, 16664.0, 16722.749158762872, 15987.200639967723, 16134.757978630565, 16300.592485187964, 16394.672106557562, 16420.75368869444, 10951.233413661286, 11210.831837522606, 11405.0, 11568.595099171489, 12000.879991199976, 12373.0, 13093.541726039542, 13145.145565538209, 13151.73696526045, 13320.866791331962], "value": 8054.323784819916, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1448.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 113.0, 112.0, 114.0, 113.0, 112.0, 114.0, 113.0, 113.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 118.0, 114.0, 155.0, 116.0, 117.0, 117.0, 116.0, 137.0, 120.0], "value": 125.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 162.0, 161.0, 161.0, 161.0, 161.0, 161.0, 160.0, 161.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 102.0, 76.0, 76.0, 75.0, 75.0, 91.0, 74.0], "value": 79.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 100.0, 82.0, 82.0, 83.0, 104.0, 83.0, 82.0, 98.0], "value": 87.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 113.0, 113.0, 113.0, 112.0, 113.0, 112.0, 113.0, 113.0], "value": 112.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 146.0, 147.0, 147.0, 147.0, 146.0, 147.0, 146.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 93.0, 94.0, 93.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 53.0, 54.0, 53.0, 54.0, 54.0, 54.0, 54.0, 53.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 54.0, 53.0, 53.0, 53.0, 52.0, 53.0, 53.0, 53.0], "value": 52.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 76.0, 80.0, 80.0, 76.0, 81.0, 81.0, 80.0, 77.0], "value": 78.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 121.0, 119.0, 120.0, 123.0, 126.0, 127.0, 118.0, 181.0, 121.0], "value": 127.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 158.0, 160.0, 157.0, 156.0, 155.0, 155.0, 156.0, 157.0, 158.0], "value": 156.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 63.0, 59.0, 60.0, 62.0, 58.0, 58.0, 59.0, 58.0], "value": 59.7, "unit": "score"}]}]} 04:40:11 INFO - Return code: 0 04:40:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:40:11 INFO - # TBPL SUCCESS # 04:40:11 INFO - Running post-action listener: _resource_record_post_action 04:40:11 INFO - [mozharness: 2017-05-16 11:40:11.562062Z] Finished run-tests step (success) 04:40:11 INFO - Running post-run listener: _resource_record_post_run 04:40:11 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 326426624; Read time: 40; Write time: 187624 04:40:11 INFO - TinderboxPrint: CPU usage
13.1% 04:40:11 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 40 04:40:11 INFO - TinderboxPrint: I/O write bytes / time
326,426,624 / 187,624 04:40:11 INFO - TinderboxPrint: CPU idle
8,255.5 (86.9%) 04:40:11 INFO - TinderboxPrint: CPU user
1,197.5 (12.6%) 04:40:11 INFO - TinderboxPrint: Swap in / out
0 / 0 04:40:11 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 48680960; Read time: 0; Write time: 19424 04:40:11 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 227135488; Read time: 0; Write time: 95168 04:40:12 INFO - Running post-run listener: _upload_blobber_files 04:40:12 INFO - Blob upload gear active. 04:40:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:40:12 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:40:12 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', 'Mozilla-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:40:12 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 Mozilla-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:40:12 INFO - (blobuploader) - INFO - Open directory for files ... 04:40:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:40:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:40:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:40:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:40:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:40:13 INFO - (blobuploader) - INFO - Done attempting. 04:40:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:40:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:40:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:40:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:40:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:40:16 INFO - (blobuploader) - INFO - Done attempting. 04:40:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:40:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:40:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:40:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:40:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:40:17 INFO - (blobuploader) - INFO - Done attempting. 04:40:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:40:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:40:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:40:18 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:40:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:40:18 INFO - (blobuploader) - INFO - Done attempting. 04:40:18 INFO - (blobuploader) - INFO - Iteration through files over. 04:40:18 INFO - Return code: 0 04:40:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:40:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:40:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b04765cfbe3da3afd77f445a60431dc3aaf79745017c54035686388d9218f1d042347575f846b7fb030a03033d9b17a934c828ff3af674010d161f95cadd3444", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/d0974c39b18090b6ac3ee4b43a1e75fb36c1c60483fa4f7c9c713448bdf83290083a3e7db48731999e7e1edcec2e7c5b63f70348ec3b0fda95e1821a4b2baacb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/567e241ef8fca2b642bd871d509859b67f09490c28ee02deec3cfb6e96d04d1eb08b2bd3bef44815fc96e8f6544042c0c58eb4bb2fb5ba7b8a854e872cb99753", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8a01e4c9cb409ba536665eb1a5f06ddc22d8998499e625924dedae8c7f52cf2d92120e3c3f00628ba51bf3d179b63317123ddee0945af67c3b64bc2570857623"} 04:40:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:40:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:40:18 INFO - Contents: 04:40:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b04765cfbe3da3afd77f445a60431dc3aaf79745017c54035686388d9218f1d042347575f846b7fb030a03033d9b17a934c828ff3af674010d161f95cadd3444", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/d0974c39b18090b6ac3ee4b43a1e75fb36c1c60483fa4f7c9c713448bdf83290083a3e7db48731999e7e1edcec2e7c5b63f70348ec3b0fda95e1821a4b2baacb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/567e241ef8fca2b642bd871d509859b67f09490c28ee02deec3cfb6e96d04d1eb08b2bd3bef44815fc96e8f6544042c0c58eb4bb2fb5ba7b8a854e872cb99753", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8a01e4c9cb409ba536665eb1a5f06ddc22d8998499e625924dedae8c7f52cf2d92120e3c3f00628ba51bf3d179b63317123ddee0945af67c3b64bc2570857623"} 04:40:18 INFO - Running post-run listener: copy_logs_to_upload_dir 04:40:18 INFO - Copying logs to upload dir... 04:40:18 INFO - mkdir: /builds/slave/test/build/upload/logs 04:40:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.156064 ========= master_lag: -0.12 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2017-05-16 04:40:18.004276) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-16 04:40:18.009102) ========= 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=232498196343f89f466a4a9a000001c8-1494933103.963941-1867037473 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b04765cfbe3da3afd77f445a60431dc3aaf79745017c54035686388d9218f1d042347575f846b7fb030a03033d9b17a934c828ff3af674010d161f95cadd3444", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/d0974c39b18090b6ac3ee4b43a1e75fb36c1c60483fa4f7c9c713448bdf83290083a3e7db48731999e7e1edcec2e7c5b63f70348ec3b0fda95e1821a4b2baacb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/567e241ef8fca2b642bd871d509859b67f09490c28ee02deec3cfb6e96d04d1eb08b2bd3bef44815fc96e8f6544042c0c58eb4bb2fb5ba7b8a854e872cb99753", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8a01e4c9cb409ba536665eb1a5f06ddc22d8998499e625924dedae8c7f52cf2d92120e3c3f00628ba51bf3d179b63317123ddee0945af67c3b64bc2570857623"} build_url:https://queue.taskcluster.net/v1/task/L-f7PxLGS6uCRwL-jYvz5g/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011415 build_url: 'https://queue.taskcluster.net/v1/task/L-f7PxLGS6uCRwL-jYvz5g/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b04765cfbe3da3afd77f445a60431dc3aaf79745017c54035686388d9218f1d042347575f846b7fb030a03033d9b17a934c828ff3af674010d161f95cadd3444", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/d0974c39b18090b6ac3ee4b43a1e75fb36c1c60483fa4f7c9c713448bdf83290083a3e7db48731999e7e1edcec2e7c5b63f70348ec3b0fda95e1821a4b2baacb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/567e241ef8fca2b642bd871d509859b67f09490c28ee02deec3cfb6e96d04d1eb08b2bd3bef44815fc96e8f6544042c0c58eb4bb2fb5ba7b8a854e872cb99753", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8a01e4c9cb409ba536665eb1a5f06ddc22d8998499e625924dedae8c7f52cf2d92120e3c3f00628ba51bf3d179b63317123ddee0945af67c3b64bc2570857623"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-16 04:40:18.038134) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 04:40:18.038556) ========= 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=232498196343f89f466a4a9a000001c8-1494933103.963941-1867037473 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004591 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 04:40:18.119534) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-16 04:40:18.122889) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-16 04:40:18.123220) ========= ========= Total master_lag: 0.04 =========